Results for igt@kms_flip@flip-vs-expired-vblank-interruptible@a-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json guc_logs7.tar i915_display_info_post_exec7 boot7 dmesg7

DetailValue
Duration 6.46 seconds
Hostname 
shard-dg2-4
Igt-Version 
IGT-Version: 2.3-g020b7f0da (x86_64) (Linux: 6.19.0-rc8-CI_DRM_17930-g241994730989+ x86_64)
Out 
Using IGT_SRANDOM=1770228978 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (6.460s)
Err 
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2050) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2050) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2050) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2050) DEBUG: Vblank took 53us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.101624
last_received_ts = 75.101181
last_seq = 1136
current_ts = 75.118301
current_received_ts = 75.117928
current_seq = 1137
count = 247
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 75us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.118301
last_received_ts = 75.117928
last_seq = 1137
current_ts = 75.134972
current_received_ts = 75.134598
current_seq = 1138
count = 248
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.134972
last_received_ts = 75.134598
last_seq = 1138
current_ts = 75.151649
current_received_ts = 75.151314
current_seq = 1139
count = 249
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.151649
last_received_ts = 75.151314
last_seq = 1139
current_ts = 75.168312
current_received_ts = 75.167938
current_seq = 1140
count = 250
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 81us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.168312
last_received_ts = 75.167938
last_seq = 1140
current_ts = 75.184990
current_received_ts = 75.184586
current_seq = 1141
count = 251
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.184990
last_received_ts = 75.184586
last_seq = 1141
current_ts = 75.201660
current_received_ts = 75.201317
current_seq = 1142
count = 252
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.201660
last_received_ts = 75.201317
last_seq = 1142
current_ts = 75.218330
current_received_ts = 75.217957
current_seq = 1143
count = 253
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 76us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.218330
last_received_ts = 75.217957
last_seq = 1143
current_ts = 75.235008
current_received_ts = 75.234634
current_seq = 1144
count = 254
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.235008
last_received_ts = 75.234634
last_seq = 1144
current_ts = 75.251686
current_received_ts = 75.251320
current_seq = 1145
count = 255
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.251686
last_received_ts = 75.251320
last_seq = 1145
current_ts = 75.268356
current_received_ts = 75.267975
current_seq = 1146
count = 256
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 56us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.268356
last_received_ts = 75.267975
last_seq = 1146
current_ts = 75.285027
current_received_ts = 75.284653
current_seq = 1147
count = 257
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.285027
last_received_ts = 75.284653
last_seq = 1147
current_ts = 75.301704
current_received_ts = 75.301315
current_seq = 1148
count = 258
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.301704
last_received_ts = 75.301315
last_seq = 1148
current_ts = 75.318375
current_received_ts = 75.317871
current_seq = 1149
count = 259
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.318375
last_received_ts = 75.317871
last_seq = 1149
current_ts = 75.335052
current_received_ts = 75.334671
current_seq = 1150
count = 260
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.335052
last_received_ts = 75.334671
last_seq = 1150
current_ts = 75.351723
current_received_ts = 75.351318
current_seq = 1151
count = 261
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.351723
last_received_ts = 75.351318
last_seq = 1151
current_ts = 75.368385
current_received_ts = 75.368034
current_seq = 1152
count = 262
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 87us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.368385
last_received_ts = 75.368034
last_seq = 1152
current_ts = 75.385056
current_received_ts = 75.384682
current_seq = 1153
count = 263
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.385056
last_received_ts = 75.384682
last_seq = 1153
current_ts = 75.401741
current_received_ts = 75.401321
current_seq = 1154
count = 264
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.401741
last_received_ts = 75.401321
last_seq = 1154
current_ts = 75.418404
current_received_ts = 75.418045
current_seq = 1155
count = 265
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.418404
last_received_ts = 75.418045
last_seq = 1155
current_ts = 75.435074
current_received_ts = 75.434700
current_seq = 1156
count = 266
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.435074
last_received_ts = 75.434700
last_seq = 1156
current_ts = 75.451744
current_received_ts = 75.451363
current_seq = 1157
count = 267
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.451744
last_received_ts = 75.451363
last_seq = 1157
current_ts = 75.468430
current_received_ts = 75.468063
current_seq = 1158
count = 268
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.468430
last_received_ts = 75.468063
last_seq = 1158
current_ts = 75.485100
current_received_ts = 75.484711
current_seq = 1159
count = 269
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.485100
last_received_ts = 75.484711
last_seq = 1159
current_ts = 75.501770
current_received_ts = 75.501320
current_seq = 1160
count = 270
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.501770
last_received_ts = 75.501320
last_seq = 1160
current_ts = 75.518433
current_received_ts = 75.518082
current_seq = 1161
count = 271
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.518433
last_received_ts = 75.518082
last_seq = 1161
current_ts = 75.535118
current_received_ts = 75.534721
current_seq = 1162
count = 272
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.535118
last_received_ts = 75.534721
last_seq = 1162
current_ts = 75.551788
current_received_ts = 75.551346
current_seq = 1163
count = 273
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.551788
last_received_ts = 75.551346
last_seq = 1163
current_ts = 75.568459
current_received_ts = 75.568115
current_seq = 1164
count = 274
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.568459
last_received_ts = 75.568115
last_seq = 1164
current_ts = 75.585136
current_received_ts = 75.584755
current_seq = 1165
count = 275
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.585136
last_received_ts = 75.584755
last_seq = 1165
current_ts = 75.601799
current_received_ts = 75.601357
current_seq = 1166
count = 276
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.601799
last_received_ts = 75.601357
last_seq = 1166
current_ts = 75.618469
current_received_ts = 75.617989
current_seq = 1167
count = 277
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.618469
last_received_ts = 75.617989
last_seq = 1167
current_ts = 75.635147
current_received_ts = 75.634766
current_seq = 1168
count = 278
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.635147
last_received_ts = 75.634766
last_seq = 1168
current_ts = 75.651817
current_received_ts = 75.651413
current_seq = 1169
count = 279
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.651817
last_received_ts = 75.651413
last_seq = 1169
current_ts = 75.668488
current_received_ts = 75.668175
current_seq = 1170
count = 280
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.668488
last_received_ts = 75.668175
last_seq = 1170
current_ts = 75.685158
current_received_ts = 75.684784
current_seq = 1171
count = 281
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.685158
last_received_ts = 75.684784
last_seq = 1171
current_ts = 75.701843
current_received_ts = 75.701416
current_seq = 1172
count = 282
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.701843
last_received_ts = 75.701416
last_seq = 1172
current_ts = 75.718506
current_received_ts = 75.718163
current_seq = 1173
count = 283
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.718506
last_received_ts = 75.718163
last_seq = 1173
current_ts = 75.735184
current_received_ts = 75.734802
current_seq = 1174
count = 284
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.735184
last_received_ts = 75.734802
last_seq = 1174
current_ts = 75.751846
current_received_ts = 75.751434
current_seq = 1175
count = 285
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 53us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.751846
last_received_ts = 75.751434
last_seq = 1175
current_ts = 75.768524
current_received_ts = 75.768143
current_seq = 1176
count = 286
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 53us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.768524
last_received_ts = 75.768143
last_seq = 1176
current_ts = 75.785202
current_received_ts = 75.784821
current_seq = 1177
count = 287
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.785202
last_received_ts = 75.784821
last_seq = 1177
current_ts = 75.801865
current_received_ts = 75.801445
current_seq = 1178
count = 288
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.801865
last_received_ts = 75.801445
last_seq = 1178
current_ts = 75.818550
current_received_ts = 75.818146
current_seq = 1179
count = 289
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.818550
last_received_ts = 75.818146
last_seq = 1179
current_ts = 75.835220
current_received_ts = 75.834816
current_seq = 1180
count = 290
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.835220
last_received_ts = 75.834816
last_seq = 1180
current_ts = 75.851891
current_received_ts = 75.851509
current_seq = 1181
count = 291
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 53us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.851891
last_received_ts = 75.851509
last_seq = 1181
current_ts = 75.868561
current_received_ts = 75.868134
current_seq = 1182
count = 292
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.868561
last_received_ts = 75.868134
last_seq = 1182
current_ts = 75.885223
current_received_ts = 75.884857
current_seq = 1183
count = 293
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.885223
last_received_ts = 75.884857
last_seq = 1183
current_ts = 75.901909
current_received_ts = 75.901527
current_seq = 1184
count = 294
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.901909
last_received_ts = 75.901527
last_seq = 1184
current_ts = 75.918579
current_received_ts = 75.918106
current_seq = 1185
count = 295
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.918579
last_received_ts = 75.918106
last_seq = 1185
current_ts = 75.935249
current_received_ts = 75.934875
current_seq = 1186
count = 296
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.935249
last_received_ts = 75.934875
last_seq = 1186
current_ts = 75.951927
current_received_ts = 75.951538
current_seq = 1187
count = 297
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.951927
last_received_ts = 75.951538
last_seq = 1187
current_ts = 75.968597
current_received_ts = 75.968163
current_seq = 1188
count = 298
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.968597
last_received_ts = 75.968163
last_seq = 1188
current_ts = 75.985268
current_received_ts = 75.984894
current_seq = 1189
count = 299
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 75.985268
last_received_ts = 75.984894
last_seq = 1189
current_ts = 76.001945
current_received_ts = 76.001396
current_seq = 1190
count = 300
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.001945
last_received_ts = 76.001396
last_seq = 1190
current_ts = 76.018608
current_received_ts = 76.018257
current_seq = 1191
count = 301
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.018608
last_received_ts = 76.018257
last_seq = 1191
current_ts = 76.035286
current_received_ts = 76.034904
current_seq = 1192
count = 302
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.035286
last_received_ts = 76.034904
last_seq = 1192
current_ts = 76.051956
current_received_ts = 76.051613
current_seq = 1193
count = 303
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.051956
last_received_ts = 76.051613
last_seq = 1193
current_ts = 76.068619
current_received_ts = 76.068275
current_seq = 1194
count = 304
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.068619
last_received_ts = 76.068275
last_seq = 1194
current_ts = 76.085304
current_received_ts = 76.084915
current_seq = 1195
count = 305
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.085304
last_received_ts = 76.084915
last_seq = 1195
current_ts = 76.101974
current_received_ts = 76.101593
current_seq = 1196
count = 306
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.101974
last_received_ts = 76.101593
last_seq = 1196
current_ts = 76.118645
current_received_ts = 76.118317
current_seq = 1197
count = 307
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.118645
last_received_ts = 76.118317
last_seq = 1197
current_ts = 76.135315
current_received_ts = 76.134911
current_seq = 1198
count = 308
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.135315
last_received_ts = 76.134911
last_seq = 1198
current_ts = 76.151985
current_received_ts = 76.151611
current_seq = 1199
count = 309
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.151985
last_received_ts = 76.151611
last_seq = 1199
current_ts = 76.168663
current_received_ts = 76.168266
current_seq = 1200
count = 310
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.168663
last_received_ts = 76.168266
last_seq = 1200
current_ts = 76.185333
current_received_ts = 76.184959
current_seq = 1201
count = 311
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 76us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.185333
last_received_ts = 76.184959
last_seq = 1201
current_ts = 76.202003
current_received_ts = 76.201630
current_seq = 1202
count = 312
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.202003
last_received_ts = 76.201630
last_seq = 1202
current_ts = 76.218681
current_received_ts = 76.218323
current_seq = 1203
count = 313
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.218681
last_received_ts = 76.218323
last_seq = 1203
current_ts = 76.235352
current_received_ts = 76.234978
current_seq = 1204
count = 314
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.235352
last_received_ts = 76.234978
last_seq = 1204
current_ts = 76.252029
current_received_ts = 76.251640
current_seq = 1205
count = 315
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.252029
last_received_ts = 76.251640
last_seq = 1205
current_ts = 76.268692
current_received_ts = 76.268326
current_seq = 1206
count = 316
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.268692
last_received_ts = 76.268326
last_seq = 1206
current_ts = 76.285370
current_received_ts = 76.284988
current_seq = 1207
count = 317
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.285370
last_received_ts = 76.284988
last_seq = 1207
current_ts = 76.302032
current_received_ts = 76.301628
current_seq = 1208
count = 318
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.302032
last_received_ts = 76.301628
last_seq = 1208
current_ts = 76.318710
current_received_ts = 76.318329
current_seq = 1209
count = 319
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.318710
last_received_ts = 76.318329
last_seq = 1209
current_ts = 76.335388
current_received_ts = 76.335030
current_seq = 1210
count = 320
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.335388
last_received_ts = 76.335030
last_seq = 1210
current_ts = 76.352051
current_received_ts = 76.351707
current_seq = 1211
count = 321
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.352051
last_received_ts = 76.351707
last_seq = 1211
current_ts = 76.368729
current_received_ts = 76.368324
current_seq = 1212
count = 322
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.368729
last_received_ts = 76.368324
last_seq = 1212
current_ts = 76.385391
current_received_ts = 76.385048
current_seq = 1213
count = 323
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.385391
last_received_ts = 76.385048
last_seq = 1213
current_ts = 76.402077
current_received_ts = 76.401688
current_seq = 1214
count = 324
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.402077
last_received_ts = 76.401688
last_seq = 1214
current_ts = 76.418739
current_received_ts = 76.418327
current_seq = 1215
count = 325
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.418739
last_received_ts = 76.418327
last_seq = 1215
current_ts = 76.435417
current_received_ts = 76.434883
current_seq = 1216
count = 326
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.435417
last_received_ts = 76.434883
last_seq = 1216
current_ts = 76.452087
current_received_ts = 76.451714
current_seq = 1217
count = 327
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.452087
last_received_ts = 76.451714
last_seq = 1217
current_ts = 76.468758
current_received_ts = 76.468307
current_seq = 1218
count = 328
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.468758
last_received_ts = 76.468307
last_seq = 1218
current_ts = 76.485428
current_received_ts = 76.485077
current_seq = 1219
count = 329
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 56us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.485428
last_received_ts = 76.485077
last_seq = 1219
current_ts = 76.502106
current_received_ts = 76.501724
current_seq = 1220
count = 330
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.502106
last_received_ts = 76.501724
last_seq = 1220
current_ts = 76.518776
current_received_ts = 76.518288
current_seq = 1221
count = 331
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.518776
last_received_ts = 76.518288
last_seq = 1221
current_ts = 76.535454
current_received_ts = 76.535095
current_seq = 1222
count = 332
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.535454
last_received_ts = 76.535095
last_seq = 1222
current_ts = 76.552124
current_received_ts = 76.551743
current_seq = 1223
count = 333
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.552124
last_received_ts = 76.551743
last_seq = 1223
current_ts = 76.568787
current_received_ts = 76.568352
current_seq = 1224
count = 334
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.568787
last_received_ts = 76.568352
last_seq = 1224
current_ts = 76.585464
current_received_ts = 76.585121
current_seq = 1225
count = 335
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.585464
last_received_ts = 76.585121
last_seq = 1225
current_ts = 76.602135
current_received_ts = 76.601723
current_seq = 1226
count = 336
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.602135
last_received_ts = 76.601723
last_seq = 1226
current_ts = 76.618805
current_received_ts = 76.618362
current_seq = 1227
count = 337
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.618805
last_received_ts = 76.618362
last_seq = 1227
current_ts = 76.635483
current_received_ts = 76.635132
current_seq = 1228
count = 338
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.635483
last_received_ts = 76.635132
last_seq = 1228
current_ts = 76.652153
current_received_ts = 76.651726
current_seq = 1229
count = 339
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.652153
last_received_ts = 76.651726
last_seq = 1229
current_ts = 76.668823
current_received_ts = 76.668411
current_seq = 1230
count = 340
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.668823
last_received_ts = 76.668411
last_seq = 1230
current_ts = 76.685501
current_received_ts = 76.685173
current_seq = 1231
count = 341
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.685501
last_received_ts = 76.685173
last_seq = 1231
current_ts = 76.702171
current_received_ts = 76.701797
current_seq = 1232
count = 342
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 86us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.702171
last_received_ts = 76.701797
last_seq = 1232
current_ts = 76.718834
current_received_ts = 76.718422
current_seq = 1233
count = 343
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.718834
last_received_ts = 76.718422
last_seq = 1233
current_ts = 76.735512
current_received_ts = 76.735046
current_seq = 1234
count = 344
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.735512
last_received_ts = 76.735046
last_seq = 1234
current_ts = 76.752190
current_received_ts = 76.751816
current_seq = 1235
count = 345
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.752190
last_received_ts = 76.751816
last_seq = 1235
current_ts = 76.768852
current_received_ts = 76.768440
current_seq = 1236
count = 346
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.768852
last_received_ts = 76.768440
last_seq = 1236
current_ts = 76.785530
current_received_ts = 76.785172
current_seq = 1237
count = 347
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 53us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.785530
last_received_ts = 76.785172
last_seq = 1237
current_ts = 76.802200
current_received_ts = 76.801819
current_seq = 1238
count = 348
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.802200
last_received_ts = 76.801819
last_seq = 1238
current_ts = 76.818871
current_received_ts = 76.818497
current_seq = 1239
count = 349
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.818871
last_received_ts = 76.818497
last_seq = 1239
current_ts = 76.835541
current_received_ts = 76.835144
current_seq = 1240
count = 350
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.835541
last_received_ts = 76.835144
last_seq = 1240
current_ts = 76.852226
current_received_ts = 76.851845
current_seq = 1241
count = 351
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.852226
last_received_ts = 76.851845
last_seq = 1241
current_ts = 76.868889
current_received_ts = 76.868462
current_seq = 1242
count = 352
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.868889
last_received_ts = 76.868462
last_seq = 1242
current_ts = 76.885567
current_received_ts = 76.885254
current_seq = 1243
count = 353
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 57us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.885567
last_received_ts = 76.885254
last_seq = 1243
current_ts = 76.902237
current_received_ts = 76.901855
current_seq = 1244
count = 354
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.902237
last_received_ts = 76.901855
last_seq = 1244
current_ts = 76.918907
current_received_ts = 76.918533
current_seq = 1245
count = 355
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.918907
last_received_ts = 76.918533
last_seq = 1245
current_ts = 76.935585
current_received_ts = 76.935158
current_seq = 1246
count = 356
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 56us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.935585
last_received_ts = 76.935158
last_seq = 1246
current_ts = 76.952255
current_received_ts = 76.951904
current_seq = 1247
count = 357
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.952255
last_received_ts = 76.951904
last_seq = 1247
current_ts = 76.968925
current_received_ts = 76.968552
current_seq = 1248
count = 358
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.968925
last_received_ts = 76.968552
last_seq = 1248
current_ts = 76.985596
current_received_ts = 76.985161
current_seq = 1249
count = 359
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 76.985596
last_received_ts = 76.985161
last_seq = 1249
current_ts = 77.002274
current_received_ts = 77.001892
current_seq = 1250
count = 360
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.002274
last_received_ts = 77.001892
last_seq = 1250
current_ts = 77.018944
current_received_ts = 77.018562
current_seq = 1251
count = 361
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.018944
last_received_ts = 77.018562
last_seq = 1251
current_ts = 77.035606
current_received_ts = 77.035309
current_seq = 1252
count = 362
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.035606
last_received_ts = 77.035309
last_seq = 1252
current_ts = 77.052284
current_received_ts = 77.051910
current_seq = 1253
count = 363
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.052284
last_received_ts = 77.051910
last_seq = 1253
current_ts = 77.068954
current_received_ts = 77.068535
current_seq = 1254
count = 364
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 55us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.068954
last_received_ts = 77.068535
last_seq = 1254
current_ts = 77.085632
current_received_ts = 77.085251
current_seq = 1255
count = 365
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 54us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.085632
last_received_ts = 77.085251
last_seq = 1255
current_ts = 77.102303
current_received_ts = 77.101929
current_seq = 1256
count = 366
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 76us
(kms_flip:2050) DEBUG: name = flip
last_ts = 77.102303
last_received_ts = 77.101929
last_seq = 1256
current_ts = 77.118958
current_received_ts = 77.118431
current_seq = 1257
count = 367
seq_step = 1
(kms_flip:2050) DEBUG: Vblank took 593us
(kms_flip:2050) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2050) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2050) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2050) igt_core-INFO: Stack trace:
(kms_flip:2050) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2050) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:2050) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2050) igt_core-INFO:   #3 ../tests/kms_flip.c:2158 __igt_unique____real_main2045()
(kms_flip:2050) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2050) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2050) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2050) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (6.460s)
Dmesg
<6> [69.263930] Console: switching to colour dummy device 80x25
<6> [69.263992] [IGT] kms_flip: executing
<7> [69.279850] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [69.281954] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [69.296606] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [69.305628] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1]
<7> [69.305639] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:507:DP-1]
<7> [69.309915] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1] disconnected
<7> [69.310323] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:DP-2]
<7> [69.310328] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:520:DP-2]
<7> [69.310665] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [69.310963] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [69.311215] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_B
<7> [69.333107] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.354233] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.375343] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.396452] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.417562] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.438721] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.459855] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.480980] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.502125] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.523247] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.544379] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.565513] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.586662] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.607783] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.628953] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.650088] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.671224] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.692343] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.713475] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.734612] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.755742] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.776925] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.798072] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.819209] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.840331] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.861446] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.882568] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.903687] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.924838] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.945976] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.967100] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.988237] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [69.988434] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX B/DDI B/PHY B: Too many retries, giving up. First error: -110
<7> [69.988478] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:DP-2] disconnected
<7> [69.989145] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-3]
<7> [69.989151] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-3]
<7> [69.993368] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-3] disconnected
<7> [69.993711] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2]
<7> [69.993716] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2]
<7> [69.997961] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected
<7> [69.998324] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-4]
<7> [69.998328] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-4]
<7> [70.002526] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-4] disconnected
<7> [70.002871] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:HDMI-A-3]
<7> [70.002875] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:555:HDMI-A-3]
<7> [70.007184] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:HDMI-A-3] disconnected
<7> [70.007630] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1]
<7> [70.007635] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:507:DP-1]
<7> [70.011861] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:507:DP-1] disconnected
<7> [70.011875] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:DP-2]
<7> [70.011885] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:520:DP-2]
<7> [70.033062] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.054160] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.075281] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.096397] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.117523] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.138643] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.159771] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.181113] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.202238] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.223376] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.244521] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.265702] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.286844] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.307975] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.329110] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.350248] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.371384] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.392519] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.413673] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.434793] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.455913] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.477036] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.498173] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.519307] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.540444] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.561598] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.582739] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.603872] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.625011] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.646129] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.667261] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.688395] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX B/DDI B/PHY B: timeout (status 0x7d40023f)
<7> [70.688613] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX B/DDI B/PHY B: Too many retries, giving up. First error: -110
<7> [70.688663] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:520:DP-2] disconnected
<7> [70.688681] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1]
<7> [70.688686] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:529:HDMI-A-1]
<7> [70.713394] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:529:HDMI-A-1] ELD monitor FH-DP4K
<7> [70.713403] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:529:HDMI-A-1] ELD size 36, SAD count 2
<7> [70.713582] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [70.713797] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [70.714135] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [70.714308] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [70.714323] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [70.714487] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] DP dual mode adaptor (type 1 DVI) detected (max TMDS clock: 165000 kHz)
<7> [70.715202] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241700 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9 (CLOCK_HIGH)
<7> [70.715209] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 267820 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9 (CLOCK_HIGH)
<7> [70.715214] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [70.715219] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 266640 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9 (CLOCK_HIGH)
<7> [70.715224] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [70.715230] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:529:HDMI-A-1] probed modes:
<7> [70.715236] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [70.715240] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 17 151100 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9
<7> [70.715243] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 30 133910 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9
<7> [70.715246] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 30 120850 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9
<7> [70.715250] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [70.715253] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [70.715256] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [70.715260] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [70.715263] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [70.715266] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [70.715270] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [70.715273] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [70.715276] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [70.715280] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [70.715283] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [70.715286] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [70.715290] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [70.715293] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [70.715296] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [70.715300] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [70.715303] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [70.715306] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [70.715310] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [70.715313] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [70.715316] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [70.715319] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [70.715340] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-3]
<7> [70.715344] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:535:DP-3]
<7> [70.719543] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:535:DP-3] disconnected
<7> [70.719557] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2]
<7> [70.719561] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:543:HDMI-A-2]
<7> [70.723859] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:543:HDMI-A-2] disconnected
<7> [70.723873] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-4]
<7> [70.723877] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:547:DP-4]
<7> [70.728097] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:547:DP-4] disconnected
<7> [70.728110] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:HDMI-A-3]
<7> [70.728114] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:555:HDMI-A-3]
<7> [70.732314] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:555:HDMI-A-3] disconnected
<6> [70.736926] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [70.741012] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [70.741477] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:558]
<7> [70.741725] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562]
<7> [70.742213] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.742867] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.746679] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_B
<7> [70.746903] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [70.747116] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [70.747299] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [70.826343] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.826702] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.832765] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.833107] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.851407] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.851770] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [70.858831] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [70.859109] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [70.859322] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [70.859515] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [70.859765] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [70.859947] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [70.860130] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [70.860314] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [70.860497] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [70.860735] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [70.860917] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [70.861099] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [70.861281] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [70.861463] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [70.861688] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [70.861871] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [70.862054] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [70.862238] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [70.862422] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [70.862650] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [70.862830] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [70.863011] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [70.863193] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [70.863374] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [70.863616] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [70.863893] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [70.864075] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [70.864257] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [70.864441] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [70.864663] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [70.864879] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [70.865061] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [70.865241] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [70.865423] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [70.865641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [70.865824] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [70.866006] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [70.866189] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [70.866371] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [70.866610] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [70.866786] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:150:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [70.866989] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [70.867166] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:144:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [70.867342] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [70.867543] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [70.867733] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [70.867909] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [70.868103] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 0 num active planes 0
<7> [70.868283] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<7> [70.868479] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:150:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [70.868743] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [70.868925] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [70.869106] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [70.869288] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:150:pipe A] enable: no [modeset]
<7> [70.869469] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [70.869686] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [70.869868] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [70.870049] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [70.870229] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [70.870409] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [70.870704] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [70.871056] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [70.879183] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [70.895762] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [70.896093] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [70.896372] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A]
<7> [70.896637] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:508:DP-MST A]
<7> [70.896892] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST B]
<7> [70.897119] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST C]
<7> [70.897370] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST D]
<7> [70.897625] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DDI B/PHY B]
<7> [70.897903] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST A]
<7> [70.898151] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST B]
<7> [70.898399] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST C]
<7> [70.898654] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST D]
<7> [70.898900] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI C/PHY C]
<7> [70.899147] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]
<7> [70.899394] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]
<7> [70.899651] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]
<7> [70.899896] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]
<7> [70.900143] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI D/PHY D]
<7> [70.900390] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A]
<7> [70.900644] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B]
<7> [70.900891] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C]
<7> [70.901139] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D]
<7> [70.901387] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:529:HDMI-A-1]
<7> [70.901794] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [70.902158] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [70.902775] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:150:pipe A]
<7> [70.903808] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:268:pipe B]
<7> [70.904304] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:386:pipe C]
<7> [70.904508] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:504:pipe D]
<7> [70.904710] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:150:pipe A]
<7> [70.904751] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:529:HDMI-A-1]
<7> [70.904851] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:529:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [70.905116] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [70.905351] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [70.905613] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:519:DDI B/PHY B] [CRTC:150:pipe A]
<7> [70.905854] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [70.906105] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [70.906356] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [70.906614] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [70.906863] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [70.907117] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [70.907368] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [70.907625] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [70.907877] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [70.908129] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [70.908382] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [70.908654] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [70.908906] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [70.909158] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [70.909407] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [70.909664] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [70.909914] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [70.910164] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [70.910413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [70.910670] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [70.910921] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [70.911170] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [70.911422] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [70.911677] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [70.911928] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [70.912176] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [70.912427] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [70.912722] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [70.912973] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [70.913224] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [70.913475] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [70.913733] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [70.913985] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [70.914236] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [70.914492] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [70.914745] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [70.914995] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:150:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [70.915247] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:150:pipe A] fastset requirement not met, forcing full modeset
<7> [70.915533] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [70.915777] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:150:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [70.916052] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [70.916294] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:144:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [70.916545] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [70.916788] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [70.917032] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0
<7> [70.917273] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0
<7> [70.917543] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:150:pipe A] data rate 594000 num active planes 1
<7> [70.917792] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [70.918041] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [70.918307] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:150:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [70.918583] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [70.918830] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [70.919077] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [70.919328] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:150:pipe A] enable: yes [modeset]
<7> [70.919585] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [70.919837] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [70.920087] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [70.920338] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [70.920593] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [70.920841] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [70.921092] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [70.921341] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [70.921597] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [70.921847] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [70.922099] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [70.922347] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [70.922609] 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> [70.922670] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [70.922859] 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> [70.922966] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [70.923109] 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> [70.923222] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [70.923360] 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> [70.923616] 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> [70.923866] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [70.924115] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [70.924364] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [70.924624] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [70.924873] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [70.925122] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [70.925371] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [70.925626] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [70.925876] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [70.926125] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.926375] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.926630] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.926882] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [70.927130] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [70.927377] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.927632] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.927881] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [70.928131] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [70.928383] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [70.928610] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:558] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [70.928832] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [70.929053] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [70.929276] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [70.929504] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [70.929726] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [70.929946] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [70.930169] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:cursor A] fb: [NOFB], visible: no
<7> [70.931830] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [70.932183] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [70.932611] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [70.932937] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:506:DDI A/PHY A]
<7> [70.933157] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:508:DP-MST A]
<7> [70.933378] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST B]
<7> [70.933606] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST C]
<7> [70.933826] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST D]
<7> [70.934046] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:519:DDI B/PHY B]
<7> [70.934278] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:521:DP-MST A]
<7> [70.934481] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST B]
<7> [70.934676] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST C]
<7> [70.934873] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST D]
<7> [70.935071] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:534:DDI C/PHY C]
<7> [70.935268] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:536:DP-MST A]
<7> [70.935465] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST B]
<7> [70.935668] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST C]
<7> [70.935865] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST D]
<7> [70.936061] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:546:DDI D/PHY D]
<7> [70.936257] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:548:DP-MST A]
<7> [70.936454] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST B]
<7> [70.936659] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST C]
<7> [70.936857] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST D]
<7> [70.937053] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [70.937501] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [70.937826] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [70.940148] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [70.940334] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [70.957376] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:529:HDMI-A-1]
<7> [70.957722] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:150:pipe A]
<7> [77.125486] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [77.126532] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [77.127476] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<6> [77.201040] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-02-04 19:50:58