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

Machine description: shard-dg2-11

Result: Fail

git-log-oneline i915_display_info22 igt_runner22 runtimes22 results22.json results22-i915-load.json i915_display_info_post_exec22 boot22 dmesg22

DetailValue
Duration 11.06 seconds
Hostname 
shard-dg2-11
Igt-Version 
IGT-Version: 1.30-gf15379f5b (x86_64) (Linux: 6.14.0-rc5-CI_DRM_16231-g3ccfd8016ad8+ x86_64)
Out 
Using IGT_SRANDOM=1741237198 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-DP3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16782.3us +- 5.108us accuracy 0.09%
vblank interval differs from modeline! expected 16666.7us, measured 16782us +- 5.108us, difference 115.6us (22.6 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-DP3: FAIL (11.064s)
Err 
Starting subtest: plain-flip-ts-check
Starting dynamic subtest: A-DP3
(kms_flip:2009) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2009) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
Dynamic subtest A-DP3 failed.
**** DEBUG ****
(kms_flip:2009) DEBUG: flip ts/seq: last 70.159264/1137, current 70.175980/1138: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.175980
last_received_ts = 70.175583
last_seq = 1138
current_ts = 70.192711
current_received_ts = 70.192284
current_seq = 1139
count = 312
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.175980/1138, current 70.192711/1139: elapsed=16727.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.192711
last_received_ts = 70.192284
last_seq = 1139
current_ts = 70.209419
current_received_ts = 70.209053
current_seq = 1140
count = 313
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.192711/1139, current 70.209419/1140: elapsed=16710.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.209419
last_received_ts = 70.209053
last_seq = 1140
current_ts = 70.226135
current_received_ts = 70.225716
current_seq = 1141
count = 314
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.209419/1140, current 70.226135/1141: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.226135
last_received_ts = 70.225716
last_seq = 1141
current_ts = 70.242859
current_received_ts = 70.242409
current_seq = 1142
count = 315
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.226135/1141, current 70.242859/1142: elapsed=16718.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.242859
last_received_ts = 70.242409
last_seq = 1142
current_ts = 70.259583
current_received_ts = 70.259308
current_seq = 1143
count = 316
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.242859/1142, current 70.259583/1143: elapsed=16727.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.259583
last_received_ts = 70.259308
last_seq = 1143
current_ts = 70.276291
current_received_ts = 70.275871
current_seq = 1144
count = 317
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.259583/1143, current 70.276291/1144: elapsed=16711.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.276291
last_received_ts = 70.275871
last_seq = 1144
current_ts = 70.293015
current_received_ts = 70.292671
current_seq = 1145
count = 318
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.276291/1144, current 70.293015/1145: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.293015
last_received_ts = 70.292671
last_seq = 1145
current_ts = 70.309738
current_received_ts = 70.309250
current_seq = 1146
count = 319
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.293015/1145, current 70.309738/1146: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.309738
last_received_ts = 70.309250
last_seq = 1146
current_ts = 70.326454
current_received_ts = 70.326088
current_seq = 1147
count = 320
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.309738/1146, current 70.326454/1147: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.326454
last_received_ts = 70.326088
last_seq = 1147
current_ts = 70.343170
current_received_ts = 70.342751
current_seq = 1148
count = 321
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.326454/1147, current 70.343170/1148: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.343170
last_received_ts = 70.342751
last_seq = 1148
current_ts = 70.359894
current_received_ts = 70.359436
current_seq = 1149
count = 322
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.343170/1148, current 70.359894/1149: elapsed=16721.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.359894
last_received_ts = 70.359436
last_seq = 1149
current_ts = 70.376610
current_received_ts = 70.376205
current_seq = 1150
count = 323
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.359894/1149, current 70.376610/1150: elapsed=16718.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.376610
last_received_ts = 70.376205
last_seq = 1150
current_ts = 70.393326
current_received_ts = 70.392914
current_seq = 1151
count = 324
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.376610/1150, current 70.393326/1151: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.393326
last_received_ts = 70.392914
last_seq = 1151
current_ts = 70.410042
current_received_ts = 70.409622
current_seq = 1152
count = 325
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.393326/1151, current 70.410042/1152: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.410042
last_received_ts = 70.409622
last_seq = 1152
current_ts = 70.426765
current_received_ts = 70.426285
current_seq = 1153
count = 326
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.410042/1152, current 70.426765/1153: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.426765
last_received_ts = 70.426285
last_seq = 1153
current_ts = 70.443489
current_received_ts = 70.443192
current_seq = 1154
count = 327
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.426765/1153, current 70.443489/1154: elapsed=16726.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.443489
last_received_ts = 70.443192
last_seq = 1154
current_ts = 70.460205
current_received_ts = 70.459801
current_seq = 1155
count = 328
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.443489/1154, current 70.460205/1155: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.460205
last_received_ts = 70.459801
last_seq = 1155
current_ts = 70.476913
current_received_ts = 70.476486
current_seq = 1156
count = 329
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.460205/1155, current 70.476913/1156: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.476913
last_received_ts = 70.476486
last_seq = 1156
current_ts = 70.493645
current_received_ts = 70.493263
current_seq = 1157
count = 330
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.476913/1156, current 70.493645/1157: elapsed=16724.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.493645
last_received_ts = 70.493263
last_seq = 1157
current_ts = 70.510361
current_received_ts = 70.509956
current_seq = 1158
count = 331
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.493645/1157, current 70.510361/1158: elapsed=16720.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.510361
last_received_ts = 70.509956
last_seq = 1158
current_ts = 70.527077
current_received_ts = 70.526741
current_seq = 1159
count = 332
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.510361/1158, current 70.527077/1159: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.527077
last_received_ts = 70.526741
last_seq = 1159
current_ts = 70.543800
current_received_ts = 70.543373
current_seq = 1160
count = 333
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.527077/1159, current 70.543800/1160: elapsed=16718.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.543800
last_received_ts = 70.543373
last_seq = 1160
current_ts = 70.560524
current_received_ts = 70.560211
current_seq = 1161
count = 334
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.543800/1160, current 70.560524/1161: elapsed=16727.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.560524
last_received_ts = 70.560211
last_seq = 1161
current_ts = 70.577232
current_received_ts = 70.576927
current_seq = 1162
count = 335
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.560524/1161, current 70.577232/1162: elapsed=16707.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.577232
last_received_ts = 70.576927
last_seq = 1162
current_ts = 70.593948
current_received_ts = 70.593506
current_seq = 1163
count = 336
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.577232/1162, current 70.593948/1163: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.593948
last_received_ts = 70.593506
last_seq = 1163
current_ts = 70.610680
current_received_ts = 70.610397
current_seq = 1164
count = 337
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.593948/1163, current 70.610680/1164: elapsed=16727.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.610680
last_received_ts = 70.610397
last_seq = 1164
current_ts = 70.627388
current_received_ts = 70.627037
current_seq = 1165
count = 338
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.610680/1164, current 70.627388/1165: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.627388
last_received_ts = 70.627037
last_seq = 1165
current_ts = 70.644112
current_received_ts = 70.643707
current_seq = 1166
count = 339
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.627388/1165, current 70.644112/1166: elapsed=16725.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.644112
last_received_ts = 70.643707
last_seq = 1166
current_ts = 70.660828
current_received_ts = 70.660400
current_seq = 1167
count = 340
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.644112/1166, current 70.660828/1167: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.660828
last_received_ts = 70.660400
last_seq = 1167
current_ts = 70.677551
current_received_ts = 70.677284
current_seq = 1168
count = 341
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.660828/1167, current 70.677551/1168: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.677551
last_received_ts = 70.677284
last_seq = 1168
current_ts = 70.694267
current_received_ts = 70.693863
current_seq = 1169
count = 342
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.677551/1168, current 70.694267/1169: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.694267
last_received_ts = 70.693863
last_seq = 1169
current_ts = 70.710983
current_received_ts = 70.710648
current_seq = 1170
count = 343
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.694267/1169, current 70.710983/1170: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.710983
last_received_ts = 70.710648
last_seq = 1170
current_ts = 70.727707
current_received_ts = 70.727287
current_seq = 1171
count = 344
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.710983/1170, current 70.727707/1171: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.727707
last_received_ts = 70.727287
last_seq = 1171
current_ts = 70.744431
current_received_ts = 70.744057
current_seq = 1172
count = 345
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.727707/1171, current 70.744431/1172: elapsed=16720.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.744431
last_received_ts = 70.744057
last_seq = 1172
current_ts = 70.761139
current_received_ts = 70.760818
current_seq = 1173
count = 346
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.744431/1172, current 70.761139/1173: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.761139
last_received_ts = 70.760818
last_seq = 1173
current_ts = 70.777863
current_received_ts = 70.777435
current_seq = 1174
count = 347
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.761139/1173, current 70.777863/1174: elapsed=16724.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.777863
last_received_ts = 70.777435
last_seq = 1174
current_ts = 70.794586
current_received_ts = 70.794182
current_seq = 1175
count = 348
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.777863/1174, current 70.794586/1175: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.794586
last_received_ts = 70.794182
last_seq = 1175
current_ts = 70.811302
current_received_ts = 70.810890
current_seq = 1176
count = 349
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.794586/1175, current 70.811302/1176: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.811302
last_received_ts = 70.810890
last_seq = 1176
current_ts = 70.828018
current_received_ts = 70.827606
current_seq = 1177
count = 350
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.811302/1176, current 70.828018/1177: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.828018
last_received_ts = 70.827606
last_seq = 1177
current_ts = 70.844734
current_received_ts = 70.844292
current_seq = 1178
count = 351
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.828018/1177, current 70.844734/1178: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.844734
last_received_ts = 70.844292
last_seq = 1178
current_ts = 70.861458
current_received_ts = 70.861099
current_seq = 1179
count = 352
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.844734/1178, current 70.861458/1179: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.861458
last_received_ts = 70.861099
last_seq = 1179
current_ts = 70.878174
current_received_ts = 70.877762
current_seq = 1180
count = 353
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.861458/1179, current 70.878174/1180: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.878174
last_received_ts = 70.877762
last_seq = 1180
current_ts = 70.894897
current_received_ts = 70.894455
current_seq = 1181
count = 354
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.878174/1180, current 70.894897/1181: elapsed=16727.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.894897
last_received_ts = 70.894455
last_seq = 1181
current_ts = 70.911621
current_received_ts = 70.911217
current_seq = 1182
count = 355
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.894897/1181, current 70.911621/1182: elapsed=16723.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.911621
last_received_ts = 70.911217
last_seq = 1182
current_ts = 70.928329
current_received_ts = 70.928062
current_seq = 1183
count = 356
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.911621/1182, current 70.928329/1183: elapsed=16708.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.928329
last_received_ts = 70.928062
last_seq = 1183
current_ts = 70.945053
current_received_ts = 70.944641
current_seq = 1184
count = 357
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.928329/1183, current 70.945053/1184: elapsed=16724.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.945053
last_received_ts = 70.944641
last_seq = 1184
current_ts = 70.961761
current_received_ts = 70.961281
current_seq = 1185
count = 358
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.945053/1184, current 70.961761/1185: elapsed=16711.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.961761
last_received_ts = 70.961281
last_seq = 1185
current_ts = 70.978493
current_received_ts = 70.978096
current_seq = 1186
count = 359
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.961761/1185, current 70.978493/1186: elapsed=16726.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.978493
last_received_ts = 70.978096
last_seq = 1186
current_ts = 70.995201
current_received_ts = 70.994766
current_seq = 1187
count = 360
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.978493/1186, current 70.995201/1187: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 70.995201
last_received_ts = 70.994766
last_seq = 1187
current_ts = 71.011932
current_received_ts = 71.011520
current_seq = 1188
count = 361
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 70.995201/1187, current 71.011932/1188: elapsed=16725.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.011932
last_received_ts = 71.011520
last_seq = 1188
current_ts = 71.028641
current_received_ts = 71.028191
current_seq = 1189
count = 362
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.011932/1188, current 71.028641/1189: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.028641
last_received_ts = 71.028191
last_seq = 1189
current_ts = 71.045372
current_received_ts = 71.045456
current_seq = 1190
count = 363
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.028641/1189, current 71.045372/1190: elapsed=16729.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.045372
last_received_ts = 71.045456
last_seq = 1190
current_ts = 71.062088
current_received_ts = 71.061661
current_seq = 1191
count = 364
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.045372/1190, current 71.062088/1191: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.062088
last_received_ts = 71.061661
last_seq = 1191
current_ts = 71.078796
current_received_ts = 71.078316
current_seq = 1192
count = 365
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.062088/1191, current 71.078796/1192: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.078796
last_received_ts = 71.078316
last_seq = 1192
current_ts = 71.095528
current_received_ts = 71.094955
current_seq = 1193
count = 366
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.078796/1192, current 71.095528/1193: elapsed=16730.0us expected=16782.3us +- 83.9us, error 0.3%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.095528
last_received_ts = 71.094955
last_seq = 1193
current_ts = 71.112244
current_received_ts = 71.111702
current_seq = 1194
count = 367
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.095528/1193, current 71.112244/1194: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.112244
last_received_ts = 71.111702
last_seq = 1194
current_ts = 71.128960
current_received_ts = 71.128426
current_seq = 1195
count = 368
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.112244/1194, current 71.128960/1195: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.128960
last_received_ts = 71.128426
last_seq = 1195
current_ts = 71.145668
current_received_ts = 71.145142
current_seq = 1196
count = 369
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.128960/1195, current 71.145668/1196: elapsed=16707.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.145668
last_received_ts = 71.145142
last_seq = 1196
current_ts = 71.162384
current_received_ts = 71.162056
current_seq = 1197
count = 370
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.145668/1196, current 71.162384/1197: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.162384
last_received_ts = 71.162056
last_seq = 1197
current_ts = 71.179085
current_received_ts = 71.178741
current_seq = 1198
count = 371
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.162384/1197, current 71.179085/1198: elapsed=16705.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.179085
last_received_ts = 71.178741
last_seq = 1198
current_ts = 71.195808
current_received_ts = 71.195412
current_seq = 1199
count = 372
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.179085/1198, current 71.195808/1199: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.195808
last_received_ts = 71.195412
last_seq = 1199
current_ts = 71.212517
current_received_ts = 71.212166
current_seq = 1200
count = 373
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.195808/1199, current 71.212517/1200: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.212517
last_received_ts = 71.212166
last_seq = 1200
current_ts = 71.229233
current_received_ts = 71.228882
current_seq = 1201
count = 374
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.212517/1200, current 71.229233/1201: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.229233
last_received_ts = 71.228882
last_seq = 1201
current_ts = 71.245949
current_received_ts = 71.245544
current_seq = 1202
count = 375
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.229233/1201, current 71.245949/1202: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.245949
last_received_ts = 71.245544
last_seq = 1202
current_ts = 71.262657
current_received_ts = 71.262321
current_seq = 1203
count = 376
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.245949/1202, current 71.262657/1203: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.262657
last_received_ts = 71.262321
last_seq = 1203
current_ts = 71.279373
current_received_ts = 71.279060
current_seq = 1204
count = 377
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.262657/1203, current 71.279373/1204: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.279373
last_received_ts = 71.279060
last_seq = 1204
current_ts = 71.296082
current_received_ts = 71.295715
current_seq = 1205
count = 378
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.279373/1204, current 71.296082/1205: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.296082
last_received_ts = 71.295715
last_seq = 1205
current_ts = 71.312798
current_received_ts = 71.312401
current_seq = 1206
count = 379
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.296082/1205, current 71.312798/1206: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.312798
last_received_ts = 71.312401
last_seq = 1206
current_ts = 71.329506
current_received_ts = 71.329163
current_seq = 1207
count = 380
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.312798/1206, current 71.329506/1207: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.329506
last_received_ts = 71.329163
last_seq = 1207
current_ts = 71.346214
current_received_ts = 71.345856
current_seq = 1208
count = 381
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.329506/1207, current 71.346214/1208: elapsed=16707.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.346214
last_received_ts = 71.345856
last_seq = 1208
current_ts = 71.362930
current_received_ts = 71.362534
current_seq = 1209
count = 382
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.346214/1208, current 71.362930/1209: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.362930
last_received_ts = 71.362534
last_seq = 1209
current_ts = 71.379639
current_received_ts = 71.379318
current_seq = 1210
count = 383
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.362930/1209, current 71.379639/1210: elapsed=16711.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.379639
last_received_ts = 71.379318
last_seq = 1210
current_ts = 71.396355
current_received_ts = 71.395981
current_seq = 1211
count = 384
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.379639/1210, current 71.396355/1211: elapsed=16717.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.396355
last_received_ts = 71.395981
last_seq = 1211
current_ts = 71.413063
current_received_ts = 71.412781
current_seq = 1212
count = 385
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.396355/1211, current 71.413063/1212: elapsed=16708.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.413063
last_received_ts = 71.412781
last_seq = 1212
current_ts = 71.429779
current_received_ts = 71.429329
current_seq = 1213
count = 386
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.413063/1212, current 71.429779/1213: elapsed=16711.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.429779
last_received_ts = 71.429329
last_seq = 1213
current_ts = 71.446495
current_received_ts = 71.446152
current_seq = 1214
count = 387
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.429779/1213, current 71.446495/1214: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.446495
last_received_ts = 71.446152
last_seq = 1214
current_ts = 71.463203
current_received_ts = 71.462845
current_seq = 1215
count = 388
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.446495/1214, current 71.463203/1215: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.463203
last_received_ts = 71.462845
last_seq = 1215
current_ts = 71.479927
current_received_ts = 71.479530
current_seq = 1216
count = 389
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.463203/1215, current 71.479927/1216: elapsed=16723.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.479927
last_received_ts = 71.479530
last_seq = 1216
current_ts = 71.496628
current_received_ts = 71.496269
current_seq = 1217
count = 390
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.479927/1216, current 71.496628/1217: elapsed=16703.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.496628
last_received_ts = 71.496269
last_seq = 1217
current_ts = 71.513336
current_received_ts = 71.513100
current_seq = 1218
count = 391
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.496628/1217, current 71.513336/1218: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.513336
last_received_ts = 71.513100
last_seq = 1218
current_ts = 71.530060
current_received_ts = 71.529701
current_seq = 1219
count = 392
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.513336/1218, current 71.530060/1219: elapsed=16720.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.530060
last_received_ts = 71.529701
last_seq = 1219
current_ts = 71.546768
current_received_ts = 71.546333
current_seq = 1220
count = 393
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.530060/1219, current 71.546768/1220: elapsed=16705.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.546768
last_received_ts = 71.546333
last_seq = 1220
current_ts = 71.563484
current_received_ts = 71.563133
current_seq = 1221
count = 394
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.546768/1220, current 71.563484/1221: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.563484
last_received_ts = 71.563133
last_seq = 1221
current_ts = 71.580200
current_received_ts = 71.579796
current_seq = 1222
count = 395
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.563484/1221, current 71.580200/1222: elapsed=16719.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.580200
last_received_ts = 71.579796
last_seq = 1222
current_ts = 71.596901
current_received_ts = 71.596512
current_seq = 1223
count = 396
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.580200/1222, current 71.596901/1223: elapsed=16704.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.596901
last_received_ts = 71.596512
last_seq = 1223
current_ts = 71.613625
current_received_ts = 71.613266
current_seq = 1224
count = 397
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.596901/1223, current 71.613625/1224: elapsed=16720.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.613625
last_received_ts = 71.613266
last_seq = 1224
current_ts = 71.630333
current_received_ts = 71.629967
current_seq = 1225
count = 398
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.613625/1224, current 71.630333/1225: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.630333
last_received_ts = 71.629967
last_seq = 1225
current_ts = 71.647049
current_received_ts = 71.646683
current_seq = 1226
count = 399
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.630333/1225, current 71.647049/1226: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.647049
last_received_ts = 71.646683
last_seq = 1226
current_ts = 71.663757
current_received_ts = 71.663307
current_seq = 1227
count = 400
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.647049/1226, current 71.663757/1227: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.663757
last_received_ts = 71.663307
last_seq = 1227
current_ts = 71.680473
current_received_ts = 71.680122
current_seq = 1228
count = 401
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.663757/1227, current 71.680473/1228: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.680473
last_received_ts = 71.680122
last_seq = 1228
current_ts = 71.697182
current_received_ts = 71.696915
current_seq = 1229
count = 402
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.680473/1228, current 71.697182/1229: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.697182
last_received_ts = 71.696915
last_seq = 1229
current_ts = 71.713898
current_received_ts = 71.713531
current_seq = 1230
count = 403
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.697182/1229, current 71.713898/1230: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.713898
last_received_ts = 71.713531
last_seq = 1230
current_ts = 71.730606
current_received_ts = 71.730240
current_seq = 1231
count = 404
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.713898/1230, current 71.730606/1231: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.730606
last_received_ts = 71.730240
last_seq = 1231
current_ts = 71.747314
current_received_ts = 71.747086
current_seq = 1232
count = 405
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.730606/1231, current 71.747314/1232: elapsed=16705.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.747314
last_received_ts = 71.747086
last_seq = 1232
current_ts = 71.764038
current_received_ts = 71.763741
current_seq = 1233
count = 406
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.747314/1232, current 71.764038/1233: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.764038
last_received_ts = 71.763741
last_seq = 1233
current_ts = 71.780746
current_received_ts = 71.780334
current_seq = 1234
count = 407
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.764038/1233, current 71.780746/1234: elapsed=16707.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.780746
last_received_ts = 71.780334
last_seq = 1234
current_ts = 71.797455
current_received_ts = 71.797081
current_seq = 1235
count = 408
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.780746/1234, current 71.797455/1235: elapsed=16708.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.797455
last_received_ts = 71.797081
last_seq = 1235
current_ts = 71.814163
current_received_ts = 71.813805
current_seq = 1236
count = 409
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.797455/1235, current 71.814163/1236: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.814163
last_received_ts = 71.813805
last_seq = 1236
current_ts = 71.830887
current_received_ts = 71.830452
current_seq = 1237
count = 410
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.814163/1236, current 71.830887/1237: elapsed=16722.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.830887
last_received_ts = 71.830452
last_seq = 1237
current_ts = 71.847595
current_received_ts = 71.847237
current_seq = 1238
count = 411
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.830887/1237, current 71.847595/1238: elapsed=16704.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.847595
last_received_ts = 71.847237
last_seq = 1238
current_ts = 71.864311
current_received_ts = 71.864067
current_seq = 1239
count = 412
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.847595/1238, current 71.864311/1239: elapsed=16720.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.864311
last_received_ts = 71.864067
last_seq = 1239
current_ts = 71.881020
current_received_ts = 71.880669
current_seq = 1240
count = 413
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.864311/1239, current 71.881020/1240: elapsed=16707.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.881020
last_received_ts = 71.880669
last_seq = 1240
current_ts = 71.897728
current_received_ts = 71.897331
current_seq = 1241
count = 414
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.881020/1240, current 71.897728/1241: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.897728
last_received_ts = 71.897331
last_seq = 1241
current_ts = 71.914444
current_received_ts = 71.914185
current_seq = 1242
count = 415
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.897728/1241, current 71.914444/1242: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.914444
last_received_ts = 71.914185
last_seq = 1242
current_ts = 71.931152
current_received_ts = 71.930878
current_seq = 1243
count = 416
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.914444/1242, current 71.931152/1243: elapsed=16711.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.931152
last_received_ts = 71.930878
last_seq = 1243
current_ts = 71.947868
current_received_ts = 71.947479
current_seq = 1244
count = 417
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.931152/1243, current 71.947868/1244: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.947868
last_received_ts = 71.947479
last_seq = 1244
current_ts = 71.964577
current_received_ts = 71.964165
current_seq = 1245
count = 418
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.947868/1244, current 71.964577/1245: elapsed=16705.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.964577
last_received_ts = 71.964165
last_seq = 1245
current_ts = 71.981285
current_received_ts = 71.980919
current_seq = 1246
count = 419
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.964577/1245, current 71.981285/1246: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.981285
last_received_ts = 71.980919
last_seq = 1246
current_ts = 71.998001
current_received_ts = 71.997620
current_seq = 1247
count = 420
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.981285/1246, current 71.998001/1247: elapsed=16713.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 71.998001
last_received_ts = 71.997620
last_seq = 1247
current_ts = 72.014717
current_received_ts = 72.014450
current_seq = 1248
count = 421
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 71.998001/1247, current 72.014717/1248: elapsed=16714.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.014717
last_received_ts = 72.014450
last_seq = 1248
current_ts = 72.031433
current_received_ts = 72.031181
current_seq = 1249
count = 422
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.014717/1248, current 72.031433/1249: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.031433
last_received_ts = 72.031181
last_seq = 1249
current_ts = 72.048149
current_received_ts = 72.047852
current_seq = 1250
count = 423
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.031433/1249, current 72.048149/1250: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.048149
last_received_ts = 72.047852
last_seq = 1250
current_ts = 72.064850
current_received_ts = 72.064461
current_seq = 1251
count = 424
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.048149/1250, current 72.064850/1251: elapsed=16706.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.064850
last_received_ts = 72.064461
last_seq = 1251
current_ts = 72.081566
current_received_ts = 72.081345
current_seq = 1252
count = 425
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.064850/1251, current 72.081566/1252: elapsed=16712.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.081566
last_received_ts = 72.081345
last_seq = 1252
current_ts = 72.098282
current_received_ts = 72.097923
current_seq = 1253
count = 426
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.081566/1252, current 72.098282/1253: elapsed=16716.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.098282
last_received_ts = 72.097923
last_seq = 1253
current_ts = 72.114990
current_received_ts = 72.114632
current_seq = 1254
count = 427
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.098282/1253, current 72.114990/1254: elapsed=16709.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.114990
last_received_ts = 72.114632
last_seq = 1254
current_ts = 72.131706
current_received_ts = 72.131180
current_seq = 1255
count = 428
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.114990/1254, current 72.131706/1255: elapsed=16715.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.131706
last_received_ts = 72.131180
last_seq = 1255
current_ts = 72.148415
current_received_ts = 72.147858
current_seq = 1256
count = 429
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.131706/1255, current 72.148415/1256: elapsed=16708.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.148415
last_received_ts = 72.147858
last_seq = 1256
current_ts = 72.165123
current_received_ts = 72.164558
current_seq = 1257
count = 430
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.148415/1256, current 72.165123/1257: elapsed=16708.0us expected=16782.3us +- 83.9us, error 0.4%
(kms_flip:2009) DEBUG: name = flip
last_ts = 72.165123
last_received_ts = 72.164558
last_seq = 1257
current_ts = 72.181816
current_received_ts = 72.181252
current_seq = 1258
count = 431
seq_step = 1
(kms_flip:2009) DEBUG: flip ts/seq: last 72.165123/1257, current 72.181816/1258: elapsed=16695.0us expected=16782.3us +- 83.9us, error 0.5%
(kms_flip:2009) DEBUG: inconsistent flip ts/seq: last 72.165123/1257, current 72.181816/1258: elapsed=16695.0us expected=16782.3us
(kms_flip:2009) DEBUG: expected 194401, counted 431, encoder type 2
(kms_flip:2009) DEBUG: dropped frames, expected 194401, counted 431, encoder type 2
(kms_flip:2009) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674:
(kms_flip:2009) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon)
(kms_flip:2009) igt_core-INFO: Stack trace:
(kms_flip:2009) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(kms_flip:2009) igt_core-INFO:   #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0()
(kms_flip:2009) igt_core-INFO:   #2 ../tests/kms_flip.c:1845 run_test()
(kms_flip:2009) igt_core-INFO:   #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001()
(kms_flip:2009) igt_core-INFO:   #4 ../tests/kms_flip.c:2001 main()
(kms_flip:2009) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2009) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2009) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-DP3: FAIL (11.064s)
Dmesg
<6> [63.907633] Console: switching to colour dummy device 80x25
<6> [63.907683] [IGT] kms_flip: executing
<7> [63.920357] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [63.921775] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [63.934238] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [63.941170] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:DP-1]
<7> [63.941186] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:241:DP-1]
<7> [63.945891] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:DP-1] disconnected
<7> [63.946260] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:254:DP-2]
<7> [63.946271] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:254:DP-2]
<7> [63.950894] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:254:DP-2] disconnected
<7> [63.951240] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:263:HDMI-A-1]
<7> [63.951251] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:263:HDMI-A-1]
<7> [63.955864] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:263:HDMI-A-1] disconnected
<7> [63.956401] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [63.956413] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [63.956746] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [63.956920] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK on first message, retry
<7> [63.957226] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [63.957367] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [63.957372] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [63.957507] i915 0000:03:00.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpc. force bit now 1
<7> [63.959379] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [63.959383] i915 0000:03:00.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpc. force bit now 0
<7> [63.959528] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [63.959930] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-4]
<7> [63.959941] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-4]
<7> [63.964758] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-4] disconnected
<7> [63.965122] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:289:HDMI-A-3]
<7> [63.965133] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:289:HDMI-A-3]
<7> [63.969766] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:289:HDMI-A-3] disconnected
<7> [63.970203] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:DP-1]
<7> [63.970214] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:241:DP-1]
<7> [63.974726] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:241:DP-1] disconnected
<7> [63.974748] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:254:DP-2]
<7> [63.974758] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:254:DP-2]
<7> [63.979675] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:254:DP-2] disconnected
<7> [63.979696] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:263:HDMI-A-1]
<7> [63.979706] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:263:HDMI-A-1]
<7> [63.984640] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:263:HDMI-A-1] disconnected
<7> [63.984662] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:269:DP-3]
<7> [63.984673] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:269:DP-3]
<7> [63.986448] i915 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [63.993164] i915 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [63.994521] i915 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [63.995717] i915 0000:03:00.0: [drm:drm_dp_dump_desc [drm_display_helper]] AUX C/DDI C/PHY C: DP branch: OUI 00-1c-f8 dev-ID 176GB0 HW-rev 1.0 SW-rev 7.85 quirks 0x0000
<7> [63.998815] i915 0000:03:00.0: [drm:drm_dp_read_downstream_info [drm_display_helper]] AUX C/DDI C/PHY C: DPCD DFP: 0b f0 02 11
<7> [63.999650] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [ENCODER:268:DDI C/PHY C] MST support: port: yes, sink: SST, modparam: yes -> enable: SST
<7> [64.007375] i915 0000:03:00.0: [drm:intel_dp_print_rates [i915]] source rates: 162000, 216000, 270000, 324000, 432000, 540000, 648000, 810000, 1000000, 1350000
<7> [64.007575] i915 0000:03:00.0: [drm:intel_dp_print_rates [i915]] sink rates: 162000, 270000, 540000
<7> [64.007728] i915 0000:03:00.0: [drm:intel_dp_print_rates [i915]] common rates: 162000, 270000, 540000
<7> [64.014011] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.015834] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.017661] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.019521] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.021342] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.023308] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.025295] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.027280] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.030894] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.032705] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.034557] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.036416] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.038206] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.040191] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.042178] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.044164] i915 0000:03:00.0: [drm:drm_dp_i2c_do_msg [drm_display_helper]] AUX C/DDI C/PHY C: native defer
<7> [64.045802] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [64.045807] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI sink does deep color 30.
<7> [64.045810] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI sink does deep color 36.
<7> [64.045813] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI sink does deep color 48.
<7> [64.045815] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] Assigning HDMI sink color depth as 16 bpc.
<7> [64.045817] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI sink does YCRCB444 in deep color.
<7> [64.045821] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] ELD monitor DPF90435
<7> [64.045824] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [64.045827] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:269:DP-3] ELD size 40, SAD count 3
<7> [64.045840] i915 0000:03:00.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:269:DP-3] VRR capable: no
<7> [64.046002] i915 0000:03:00.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:269:DP-3] DFP max bpc 12, max dotclock 0, TMDS clock 25000-600000, PCON Max FRL BW 0Gbps
<7> [64.047426] i915 0000:03:00.0: [drm:intel_dp_set_edid [i915]] PCON ENCODER DSC DPCD: 00 00 00 00 00 00 00 00 00 00 00 00 00
<7> [64.047581] i915 0000:03:00.0: [drm:intel_dp_set_edid [i915]] [CONNECTOR:269:DP-3] RGB->YcbCr conversion? no, YCbCr 4:2:0 allowed? no, YCbCr 4:4:4->4:2:0 conversion? no
<7> [64.050622] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2880x1800": 60 390000 2880 3056 3400 3440 1800 1808 1818 1890 0x40 0x5 (CLOCK_HIGH)
<7> [64.050630] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [64.050636] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [64.050641] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "720x576i": 50 13500 720 732 795 864 576 580 586 625 0x40 0x101a (NO_INTERLACE)
<7> [64.050647] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005 (NO_STEREO)
<7> [64.050652] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x4005 (NO_STEREO)
<7> [64.050657] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015 (NO_INTERLACE)
<7> [64.050662] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x1c005 (NO_STEREO)
<7> [64.050666] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 50 74250 1280 1720 1760 1980 720 725 730 750 0x40 0x4005 (NO_STEREO)
<7> [64.050671] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 50 74250 1920 2448 2492 2640 1080 1084 1094 1125 0x40 0x20015 (NO_INTERLACE)
<7> [64.050676] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005 (NO_STEREO)
<7> [64.050681] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 24 74250 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005 (NO_STEREO)
<7> [64.050686] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 50 594000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050691] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050696] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 50 594000 4096 5064 5152 5280 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050701] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050706] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [64.050711] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x1c005 (NO_STEREO)
<7> [64.050716] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x4005 (NO_STEREO)
<7> [64.050720] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x20015 (NO_INTERLACE)
<7> [64.050725] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x1c005 (NO_STEREO)
<7> [64.050730] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 24 74176 1920 2558 2602 2750 1080 1084 1089 1125 0x40 0x4005 (NO_STEREO)
<7> [64.050735] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050740] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5 (NO_420)
<7> [64.050747] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:269:DP-3] probed modes:
<7> [64.050759] i915 0000:03:00.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> [64.050769] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [64.050778] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [64.050787] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4096x2160": 24 297000 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [64.050796] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4096x2160": 24 296703 4096 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [64.050804] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [64.050813] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [64.050821] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [64.050830] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [64.050838] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [64.050847] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1600": 60 268500 2560 2608 2640 2720 1600 1603 1609 1646 0x40 0x5
<7> [64.050856] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2224x1668": 60 245000 2224 2270 2302 2382 1668 1671 1677 1714 0x40 0x5
<7> [64.050864] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1440": 60 241500 2560 2608 2640 2720 1440 1443 1449 1481 0x40 0x5
<7> [64.050873] i915 0000:03:00.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> [64.050881] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [64.050890] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [64.050898] i915 0000:03:00.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> [64.050907] i915 0000:03:00.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> [64.050915] i915 0000:03:00.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> [64.050924] i915 0000:03:00.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> [64.050932] i915 0000:03:00.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> [64.050941] i915 0000:03:00.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> [64.050949] i915 0000:03:00.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> [64.050958] i915 0000:03:00.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> [64.050966] i915 0000:03:00.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> [64.050975] i915 0000:03:00.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> [64.050983] i915 0000:03:00.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> [64.050992] i915 0000:03:00.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> [64.051000] i915 0000:03:00.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> [64.051009] i915 0000:03:00.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> [64.051017] i915 0000:03:00.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> [64.051026] i915 0000:03:00.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> [64.051034] i915 0000:03:00.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> [64.051043] i915 0000:03:00.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> [64.051051] i915 0000:03:00.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> [64.051060] i915 0000:03:00.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> [64.051068] i915 0000:03:00.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> [64.051077] i915 0000:03:00.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> [64.051085] i915 0000:03:00.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> [64.051112] i915 0000:03:00.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> [64.051121] i915 0000:03:00.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> [64.051157] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2]
<7> [64.051167] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:277:HDMI-A-2]
<7> [64.051520] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [64.051657] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK on first message, retry
<7> [64.051964] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0050 w(1)
<7> [64.052139] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [64.052144] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] HDMI GMBUS EDID read failed, retry using GPIO bit-banging
<7> [64.052279] i915 0000:03:00.0: [drm:intel_gmbus_force_bit [i915]] enabling bit-banging on i915 gmbus dpc. force bit now 1
<7> [64.054150] [drm:drm_do_probe_ddc_edid] drm: skipping non-existent adapter i915 gmbus dpc
<7> [64.054154] i915 0000:03:00.0: [drm:intel_gmbus_force_bit [i915]] disabling bit-banging on i915 gmbus dpc. force bit now 0
<7> [64.054299] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:277:HDMI-A-2] disconnected
<7> [64.054322] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-4]
<7> [64.054331] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:281:DP-4]
<7> [64.059135] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:281:DP-4] disconnected
<7> [64.059157] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:289:HDMI-A-3]
<7> [64.059167] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:289:HDMI-A-3]
<7> [64.064098] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:289:HDMI-A-3] disconnected
<6> [64.064456] [IGT] kms_flip: starting subtest plain-flip-ts-check
<6> [64.068703] [IGT] kms_flip: starting dynamic subtest A-DP3
<7> [64.069005] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:295]
<7> [64.069078] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:296]
<7> [64.069500] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.070074] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.141288] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.141607] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.146365] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.146655] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.164015] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.164300] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [64.188664] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [64.188867] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [64.189046] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [64.189226] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [64.189404] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [64.189549] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 64 data 5190451/8388608 link 288358/524288, found tu 0, data 0/0 link 0/0)
<7> [64.189696] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000080, found 0x00000000)
<7> [64.189841] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [64.189986] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [64.190138] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [64.190354] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [64.190500] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [64.190644] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [64.190789] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [64.190934] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [64.191079] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [64.191265] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [64.191411] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [64.191556] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [64.191701] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [64.191846] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [64.191991] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [64.192171] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [64.192347] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [64.192492] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [64.192637] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [64.192782] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [64.192927] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [64.193072] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [64.193237] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [64.193383] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [64.193528] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [64.193674] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [64.193818] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [64.193963] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [64.194114] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected yes, found no)
<7> [64.194262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [64.194407] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [64.194552] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [64.194696] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 270000, found 0)
<7> [64.194841] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [64.195009] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [64.195188] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [64.195366] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [64.195507] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [64.195647] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [64.195788] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.195929] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.196071] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [64.196253] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [64.196399] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<7> [64.196559] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [64.196703] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [64.196846] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [64.196991] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [64.197172] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [64.197317] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [64.197461] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [64.197605] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [64.197749] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [64.197893] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [64.198133] i915 0000:03:00.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C] Disable audio codec on [CRTC:82:pipe A]
<7> [64.218280] i915 0000:03:00.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [64.235232] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [64.252548] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [64.252929] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_C
<7> [64.253249] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [64.253557] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [64.253848] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:242:DP-MST A]
<7> [64.254121] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:243:DP-MST B]
<7> [64.254392] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:244:DP-MST C]
<7> [64.254662] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:245:DP-MST D]
<7> [64.254943] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DDI B/PHY B]
<7> [64.255215] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:255:DP-MST A]
<7> [64.255487] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:256:DP-MST B]
<7> [64.255768] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:257:DP-MST C]
<7> [64.256040] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:258:DP-MST D]
<7> [64.256310] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:268:DDI C/PHY C]
<7> [64.256609] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST A]
<7> [64.256931] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST B]
<7> [64.257243] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST C]
<7> [64.257555] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:273:DP-MST D]
<7> [64.257888] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI D/PHY D]
<7> [64.258201] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [64.258512] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [64.258836] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [64.259147] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [64.259458] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:269:DP-3]
<7> [64.259857] i915 0000:03:00.0: [drm:intel_set_cdclk [i915]] Post changing CDCLK to 163200 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [64.260459] i915 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=408
<7> [64.260805] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [64.261242] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [64.261706] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [64.262135] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [64.262848] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [64.263691] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:186:pipe C]
<7> [64.263990] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:238:pipe D]
<7> [64.264292] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [64.264354] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:269:DP-3]
<7> [64.264520] i915 0000:03:00.0: [drm:intel_dp_compute_config_limits [i915]] [ENCODER:268:DDI C/PHY C][CRTC:82:pipe A] DP link limits: pixel clock 148500 kHz DSC off max lanes 4 max rate 540000 max pipe_bpp 36 max link_bpp 36.0000
<7> [64.264865] i915 0000:03:00.0: [drm:intel_dp_compute_link_config [i915]] DP lane count 4 clock 270000 bpp input 36 compressed 0.0000 link rate required 668250 available 1080000
<7> [64.265177] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [64.265499] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:268:DDI C/PHY C] [CRTC:82:pipe A]
<7> [64.265825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [64.266146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [64.266466] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [64.266821] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [64.267143] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in dp_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 5190451/8388608 link 288358/524288)
<7> [64.267466] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000080)
<7> [64.267799] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [64.268121] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [64.268439] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [64.268769] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [64.269089] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [64.269409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [64.269738] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [64.270058] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [64.270378] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [64.270777] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [64.271097] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [64.271419] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [64.271750] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [64.272070] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [64.272389] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [64.272721] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [64.273041] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [64.273361] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [64.273691] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [64.274010] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [64.274330] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [64.274661] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [64.274983] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [64.275302] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [64.275634] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [64.275954] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [64.276273] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [64.276603] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [64.276923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in enhanced_framing (expected no, found yes)
<7> [64.277244] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [64.277575] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [64.277895] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [64.278213] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 270000)
<7> [64.278545] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [64.278906] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [64.279219] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [64.279623] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [64.279935] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [64.280246] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [64.280567] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [64.280879] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [64.281191] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [64.281523] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 594000 num active planes 1
<7> [64.281578] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [64.281841] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [64.282107] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [64.282228] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [64.282396] i915 0000:03:00.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (74250 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz)
<7> [64.282514] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [64.282672] i915 0000:03:00.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (11602 kHz) > old min cdclk (0 kHz)
<7> [64.282941] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] Can change cdclk via squashing
<7> [64.283204] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 204000 kHz, actual 204000 kHz
<7> [64.283467] i915 0000:03:00.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [64.283744] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [64.284011] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: DP (0x80), output format: RGB, sink format: RGB
<7> [64.284276] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [64.284549] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [64.284813] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [64.285077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [64.285342] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [64.285615] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dp m_n: lanes: 4; data_m: 5190451, data_n: 8388608, link_m: 288358, link_n: 524288, tu: 64
<7> [64.285882] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dp m2_n2: lanes: 4; data_m: 0, data_n: 0, link_m: 0, link_n: 0, tu: 0
<7> [64.286147] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] fec: disabled, enhanced framing: enabled
<7> [64.286410] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sdp split: disabled
<7> [64.286701] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] psr: disabled, selective update: disabled, panel replay: disabled, selective fetch: disabled
<7> [64.286929] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] minimum HBlank: 0
<7> [64.287155] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 0, infoframes enabled: 0x0
<7> [64.287380] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 09 00 68 36 00 01 00 00 00 00 00 00 00 00
<7> [64.287615] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ELD: 61 a4 9a 00 44 50 46 39 30 34 33 35 09 07 07 11
<7> [64.287841] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ELD: 17 50 51 07 00 00 00 00
<7> [64.288068] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 1
<7> [64.288294] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0
<7> [64.288528] i915 0000:03:00.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> [64.288755] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [64.288981] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [64.289209] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [64.289437] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [64.289677] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [64.289914] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [64.290142] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 270000, pipe src: 1920x1080+0+0, pixel rate 148500
<7> [64.290368] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [64.290603] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [64.290830] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [64.291057] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [64.291284] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x41eb0, dpll_md: 0x18000000, fp0: 0x88228fe, fp1: 0x2b000020
<7> [64.291518] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [64.291745] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [64.291960] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [64.292158] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.292357] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.292561] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.292759] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [64.292957] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [64.293154] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.293352] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.293556] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [64.293754] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [64.293952] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:295] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [64.294152] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [64.294351] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [64.294556] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [64.294754] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [64.294952] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [64.295150] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [64.295348] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [64.296687] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [64.297017] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [64.297396] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [64.297621] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [64.297932] i915 0000:03:00.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 204000 kHz, VCO 1305600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [64.298151] i915 0000:03:00.0: [drm:intel_audio_cdclk_change_post.part.0 [i915]] aud_ts_cdclk set to M=60, N=510
<7> [64.298326] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:240:DDI A/PHY A]
<7> [64.298508] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:242:DP-MST A]
<7> [64.298682] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:243:DP-MST B]
<7> [64.298855] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:244:DP-MST C]
<7> [64.299027] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:245:DP-MST D]
<7> [64.299199] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:253:DDI B/PHY B]
<7> [64.299378] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:255:DP-MST A]
<7> [64.299551] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:256:DP-MST B]
<7> [64.299723] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:257:DP-MST C]
<7> [64.299895] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:258:DP-MST D]
<7> [64.300067] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:268:DDI C/PHY C]
<7> [64.300240] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:270:DP-MST A]
<7> [64.300420] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:271:DP-MST B]
<7> [64.300592] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:272:DP-MST C]
<7> [64.300765] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:273:DP-MST D]
<7> [64.300938] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:280:DDI D/PHY D]
<7> [64.301110] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:282:DP-MST A]
<7> [64.301282] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:283:DP-MST B]
<7> [64.301460] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:284:DP-MST C]
<7> [64.301633] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:285:DP-MST D]
<7> [64.301814] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [64.302095] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_C
<7> [64.303437] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [64.305699] i915 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [64.313633] i915 0000:03:00.0: [drm:intel_dp_init_lttpr_and_dprx_caps [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] LTTPR common capabilities: 00 00 00 00 00 00 00 00
<7> [64.314989] i915 0000:03:00.0: [drm:drm_dp_read_dpcd_caps [drm_display_helper]] AUX C/DDI C/PHY C: DPCD: 12 14 c4 01 01 15 01 81 00 01 04 01 0f 00 01
<7> [64.316439] i915 0000:03:00.0: [drm:intel_dp_start_link_train [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Using LINK_BW_SET value 0a
<7> [64.317778] i915 0000:03:00.0: [drm:intel_dp_set_signal_levels [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] 8b/10b, lanes: 4, vswing levels: 0/0/0/0, pre-emphasis levels: 0/0/0/0
<7> [64.317936] i915 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Using DP training pattern TPS1
<7> [64.324564] i915 0000:03:00.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Clock recovery OK
<7> [64.324720] i915 0000:03:00.0: [drm:intel_dp_program_link_training_pattern [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Using DP training pattern TPS3
<7> [64.331516] i915 0000:03:00.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Channel EQ done. DP Training successful
<7> [64.331671] i915 0000:03:00.0: [drm:intel_dp_link_train_phy [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C][DPRX] Link Training passed at link rate = 270000, lane count = 4
<7> [64.332550] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [64.332885] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [64.333061] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [64.349714] i915 0000:03:00.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:269:DP-3][ENCODER:268:DDI C/PHY C] Enable audio codec on [CRTC:82:pipe A], 40 bytes ELD
<7> [64.366550] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:269:DP-3]
<7> [64.366802] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<6> [75.133435] [IGT] kms_flip: finished subtest A-DP3, FAIL
Created at 2025-03-06 05:50:09