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

Result: Fail

integration-manifest git-log-oneline i915_display_info8 igt_runner8 runtimes8 results8.json results8-i915-load.json guc_logs8.tar i915_display_info_post_exec8 boot8 dmesg8

DetailValue
Duration 3.79 seconds
Hostname
shard-dg2-1
Igt-Version
IGT-Version: 2.4-g6f88532e2 (x86_64) (Linux: 7.1.0-rc3-Patchwork_163608v3-gb83102e9c063+ x86_64)
Out
Using IGT_SRANDOM=1778639141 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
  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:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A3: FAIL (3.788s)
Err
Starting subtest: flip-vs-expired-vblank-interruptible
Starting dynamic subtest: A-HDMI-A3
(kms_flip:2228) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2228) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2228) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest A-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2228) DEBUG: Vblank took 78us
(kms_flip:2228) DEBUG: name = flip
last_ts = 70.953377
last_received_ts = 70.952972
last_seq = 1206
current_ts = 70.970039
current_received_ts = 70.969635
current_seq = 1207
count = 86
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 70.970039
last_received_ts = 70.969635
last_seq = 1207
current_ts = 70.986710
current_received_ts = 70.986275
current_seq = 1208
count = 87
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 70.986710
last_received_ts = 70.986275
last_seq = 1208
current_ts = 71.003387
current_received_ts = 71.002907
current_seq = 1209
count = 88
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.003387
last_received_ts = 71.002907
last_seq = 1209
current_ts = 71.020050
current_received_ts = 71.019653
current_seq = 1210
count = 89
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.020050
last_received_ts = 71.019653
last_seq = 1210
current_ts = 71.036720
current_received_ts = 71.036278
current_seq = 1211
count = 90
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.036720
last_received_ts = 71.036278
last_seq = 1211
current_ts = 71.053391
current_received_ts = 71.052986
current_seq = 1212
count = 91
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.053391
last_received_ts = 71.052986
last_seq = 1212
current_ts = 71.070061
current_received_ts = 71.069656
current_seq = 1213
count = 92
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.070061
last_received_ts = 71.069656
last_seq = 1213
current_ts = 71.086723
current_received_ts = 71.086281
current_seq = 1214
count = 93
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.086723
last_received_ts = 71.086281
last_seq = 1214
current_ts = 71.103401
current_received_ts = 71.102997
current_seq = 1215
count = 94
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.103401
last_received_ts = 71.102997
last_seq = 1215
current_ts = 71.120071
current_received_ts = 71.119682
current_seq = 1216
count = 95
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.120071
last_received_ts = 71.119682
last_seq = 1216
current_ts = 71.136742
current_received_ts = 71.136276
current_seq = 1217
count = 96
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.136742
last_received_ts = 71.136276
last_seq = 1217
current_ts = 71.153404
current_received_ts = 71.153030
current_seq = 1218
count = 97
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.153404
last_received_ts = 71.153030
last_seq = 1218
current_ts = 71.170074
current_received_ts = 71.169571
current_seq = 1219
count = 98
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.170074
last_received_ts = 71.169571
last_seq = 1219
current_ts = 71.186745
current_received_ts = 71.186279
current_seq = 1220
count = 99
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.186745
last_received_ts = 71.186279
last_seq = 1220
current_ts = 71.203415
current_received_ts = 71.203033
current_seq = 1221
count = 100
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.203415
last_received_ts = 71.203033
last_seq = 1221
current_ts = 71.220093
current_received_ts = 71.219612
current_seq = 1222
count = 101
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.220093
last_received_ts = 71.219612
last_seq = 1222
current_ts = 71.236755
current_received_ts = 71.236305
current_seq = 1223
count = 102
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.236755
last_received_ts = 71.236305
last_seq = 1223
current_ts = 71.253426
current_received_ts = 71.253044
current_seq = 1224
count = 103
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.253426
last_received_ts = 71.253044
last_seq = 1224
current_ts = 71.270088
current_received_ts = 71.269638
current_seq = 1225
count = 104
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.270088
last_received_ts = 71.269638
last_seq = 1225
current_ts = 71.286758
current_received_ts = 71.286293
current_seq = 1226
count = 105
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.286758
last_received_ts = 71.286293
last_seq = 1226
current_ts = 71.303429
current_received_ts = 71.303055
current_seq = 1227
count = 106
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.303429
last_received_ts = 71.303055
last_seq = 1227
current_ts = 71.320107
current_received_ts = 71.319702
current_seq = 1228
count = 107
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.320107
last_received_ts = 71.319702
last_seq = 1228
current_ts = 71.336769
current_received_ts = 71.336327
current_seq = 1229
count = 108
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.336769
last_received_ts = 71.336327
last_seq = 1229
current_ts = 71.353439
current_received_ts = 71.353058
current_seq = 1230
count = 109
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.353439
last_received_ts = 71.353058
last_seq = 1230
current_ts = 71.370117
current_received_ts = 71.369728
current_seq = 1231
count = 110
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 51us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.370117
last_received_ts = 71.369728
last_seq = 1231
current_ts = 71.386780
current_received_ts = 71.386337
current_seq = 1232
count = 111
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.386780
last_received_ts = 71.386337
last_seq = 1232
current_ts = 71.403450
current_received_ts = 71.403069
current_seq = 1233
count = 112
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.403450
last_received_ts = 71.403069
last_seq = 1233
current_ts = 71.420120
current_received_ts = 71.419716
current_seq = 1234
count = 113
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.420120
last_received_ts = 71.419716
last_seq = 1234
current_ts = 71.436783
current_received_ts = 71.436356
current_seq = 1235
count = 114
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.436783
last_received_ts = 71.436356
last_seq = 1235
current_ts = 71.453453
current_received_ts = 71.453079
current_seq = 1236
count = 115
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.453453
last_received_ts = 71.453079
last_seq = 1236
current_ts = 71.470123
current_received_ts = 71.469727
current_seq = 1237
count = 116
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.470123
last_received_ts = 71.469727
last_seq = 1237
current_ts = 71.486794
current_received_ts = 71.486275
current_seq = 1238
count = 117
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.486794
last_received_ts = 71.486275
last_seq = 1238
current_ts = 71.503464
current_received_ts = 71.503082
current_seq = 1239
count = 118
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.503464
last_received_ts = 71.503082
last_seq = 1239
current_ts = 71.520126
current_received_ts = 71.519730
current_seq = 1240
count = 119
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.520126
last_received_ts = 71.519730
last_seq = 1240
current_ts = 71.536804
current_received_ts = 71.536354
current_seq = 1241
count = 120
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.536804
last_received_ts = 71.536354
last_seq = 1241
current_ts = 71.553474
current_received_ts = 71.553085
current_seq = 1242
count = 121
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.553474
last_received_ts = 71.553085
last_seq = 1242
current_ts = 71.570145
current_received_ts = 71.569740
current_seq = 1243
count = 122
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.570145
last_received_ts = 71.569740
last_seq = 1243
current_ts = 71.586815
current_received_ts = 71.586411
current_seq = 1244
count = 123
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.586815
last_received_ts = 71.586411
last_seq = 1244
current_ts = 71.603485
current_received_ts = 71.603096
current_seq = 1245
count = 124
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.603485
last_received_ts = 71.603096
last_seq = 1245
current_ts = 71.620148
current_received_ts = 71.619766
current_seq = 1246
count = 125
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.620148
last_received_ts = 71.619766
last_seq = 1246
current_ts = 71.636826
current_received_ts = 71.636429
current_seq = 1247
count = 126
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 50us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.636826
last_received_ts = 71.636429
last_seq = 1247
current_ts = 71.653496
current_received_ts = 71.653107
current_seq = 1248
count = 127
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.653496
last_received_ts = 71.653107
last_seq = 1248
current_ts = 71.670166
current_received_ts = 71.669769
current_seq = 1249
count = 128
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 50us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.670166
last_received_ts = 71.669769
last_seq = 1249
current_ts = 71.686829
current_received_ts = 71.686432
current_seq = 1250
count = 129
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.686829
last_received_ts = 71.686432
last_seq = 1250
current_ts = 71.703499
current_received_ts = 71.703110
current_seq = 1251
count = 130
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.703499
last_received_ts = 71.703110
last_seq = 1251
current_ts = 71.720161
current_received_ts = 71.719788
current_seq = 1252
count = 131
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 52us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.720161
last_received_ts = 71.719788
last_seq = 1252
current_ts = 71.736839
current_received_ts = 71.736450
current_seq = 1253
count = 132
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 50us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.736839
last_received_ts = 71.736450
last_seq = 1253
current_ts = 71.753502
current_received_ts = 71.753136
current_seq = 1254
count = 133
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.753502
last_received_ts = 71.753136
last_seq = 1254
current_ts = 71.770172
current_received_ts = 71.769768
current_seq = 1255
count = 134
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.770172
last_received_ts = 71.769768
last_seq = 1255
current_ts = 71.786842
current_received_ts = 71.786438
current_seq = 1256
count = 135
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.786842
last_received_ts = 71.786438
last_seq = 1256
current_ts = 71.803513
current_received_ts = 71.803146
current_seq = 1257
count = 136
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.803513
last_received_ts = 71.803146
last_seq = 1257
current_ts = 71.820183
current_received_ts = 71.819778
current_seq = 1258
count = 137
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 63us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.820183
last_received_ts = 71.819778
last_seq = 1258
current_ts = 71.836861
current_received_ts = 71.836449
current_seq = 1259
count = 138
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.836861
last_received_ts = 71.836449
last_seq = 1259
current_ts = 71.853523
current_received_ts = 71.853035
current_seq = 1260
count = 139
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.853523
last_received_ts = 71.853035
last_seq = 1260
current_ts = 71.870201
current_received_ts = 71.869797
current_seq = 1261
count = 140
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.870201
last_received_ts = 71.869797
last_seq = 1261
current_ts = 71.886871
current_received_ts = 71.886452
current_seq = 1262
count = 141
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.886871
last_received_ts = 71.886452
last_seq = 1262
current_ts = 71.903526
current_received_ts = 71.903061
current_seq = 1263
count = 142
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.903526
last_received_ts = 71.903061
last_seq = 1263
current_ts = 71.920197
current_received_ts = 71.919792
current_seq = 1264
count = 143
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.920197
last_received_ts = 71.919792
last_seq = 1264
current_ts = 71.936867
current_received_ts = 71.936462
current_seq = 1265
count = 144
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.936867
last_received_ts = 71.936462
last_seq = 1265
current_ts = 71.953537
current_received_ts = 71.953041
current_seq = 1266
count = 145
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 45us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.953537
last_received_ts = 71.953041
last_seq = 1266
current_ts = 71.970215
current_received_ts = 71.969810
current_seq = 1267
count = 146
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 64us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.970215
last_received_ts = 71.969810
last_seq = 1267
current_ts = 71.986885
current_received_ts = 71.986481
current_seq = 1268
count = 147
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 71.986885
last_received_ts = 71.986481
last_seq = 1268
current_ts = 72.003540
current_received_ts = 72.003059
current_seq = 1269
count = 148
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.003540
last_received_ts = 72.003059
last_seq = 1269
current_ts = 72.020218
current_received_ts = 72.019844
current_seq = 1270
count = 149
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 51us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.020218
last_received_ts = 72.019844
last_seq = 1270
current_ts = 72.036880
current_received_ts = 72.036491
current_seq = 1271
count = 150
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.036880
last_received_ts = 72.036491
last_seq = 1271
current_ts = 72.053551
current_received_ts = 72.053703
current_seq = 1272
count = 151
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.053551
last_received_ts = 72.053703
last_seq = 1272
current_ts = 72.070229
current_received_ts = 72.069824
current_seq = 1273
count = 152
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.070229
last_received_ts = 72.069824
last_seq = 1273
current_ts = 72.086891
current_received_ts = 72.086494
current_seq = 1274
count = 153
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.086891
last_received_ts = 72.086494
last_seq = 1274
current_ts = 72.103561
current_received_ts = 72.103127
current_seq = 1275
count = 154
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.103561
last_received_ts = 72.103127
last_seq = 1275
current_ts = 72.120232
current_received_ts = 72.119827
current_seq = 1276
count = 155
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.120232
last_received_ts = 72.119827
last_seq = 1276
current_ts = 72.136902
current_received_ts = 72.136497
current_seq = 1277
count = 156
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.136902
last_received_ts = 72.136497
last_seq = 1277
current_ts = 72.153580
current_received_ts = 72.153198
current_seq = 1278
count = 157
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.153580
last_received_ts = 72.153198
last_seq = 1278
current_ts = 72.170242
current_received_ts = 72.169846
current_seq = 1279
count = 158
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.170242
last_received_ts = 72.169846
last_seq = 1279
current_ts = 72.186913
current_received_ts = 72.186508
current_seq = 1280
count = 159
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.186913
last_received_ts = 72.186508
last_seq = 1280
current_ts = 72.203575
current_received_ts = 72.203201
current_seq = 1281
count = 160
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.203575
last_received_ts = 72.203201
last_seq = 1281
current_ts = 72.220245
current_received_ts = 72.219849
current_seq = 1282
count = 161
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.220245
last_received_ts = 72.219849
last_seq = 1282
current_ts = 72.236923
current_received_ts = 72.236519
current_seq = 1283
count = 162
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.236923
last_received_ts = 72.236519
last_seq = 1283
current_ts = 72.253593
current_received_ts = 72.253212
current_seq = 1284
count = 163
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.253593
last_received_ts = 72.253212
last_seq = 1284
current_ts = 72.270264
current_received_ts = 72.269859
current_seq = 1285
count = 164
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.270264
last_received_ts = 72.269859
last_seq = 1285
current_ts = 72.286926
current_received_ts = 72.286522
current_seq = 1286
count = 165
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.286926
last_received_ts = 72.286522
last_seq = 1286
current_ts = 72.303596
current_received_ts = 72.303230
current_seq = 1287
count = 166
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.303596
last_received_ts = 72.303230
last_seq = 1287
current_ts = 72.320259
current_received_ts = 72.319870
current_seq = 1288
count = 167
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.320259
last_received_ts = 72.319870
last_seq = 1288
current_ts = 72.336937
current_received_ts = 72.336540
current_seq = 1289
count = 168
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.336937
last_received_ts = 72.336540
last_seq = 1289
current_ts = 72.353600
current_received_ts = 72.353203
current_seq = 1290
count = 169
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.353600
last_received_ts = 72.353203
last_seq = 1290
current_ts = 72.370277
current_received_ts = 72.369873
current_seq = 1291
count = 170
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.370277
last_received_ts = 72.369873
last_seq = 1291
current_ts = 72.386948
current_received_ts = 72.386543
current_seq = 1292
count = 171
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.386948
last_received_ts = 72.386543
last_seq = 1292
current_ts = 72.403603
current_received_ts = 72.403206
current_seq = 1293
count = 172
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.403603
last_received_ts = 72.403206
last_seq = 1293
current_ts = 72.420280
current_received_ts = 72.419876
current_seq = 1294
count = 173
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.420280
last_received_ts = 72.419876
last_seq = 1294
current_ts = 72.436951
current_received_ts = 72.436546
current_seq = 1295
count = 174
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.436951
last_received_ts = 72.436546
last_seq = 1295
current_ts = 72.453613
current_received_ts = 72.453239
current_seq = 1296
count = 175
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.453613
last_received_ts = 72.453239
last_seq = 1296
current_ts = 72.470291
current_received_ts = 72.469894
current_seq = 1297
count = 176
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.470291
last_received_ts = 72.469894
last_seq = 1297
current_ts = 72.486961
current_received_ts = 72.486557
current_seq = 1298
count = 177
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.486961
last_received_ts = 72.486557
last_seq = 1298
current_ts = 72.503632
current_received_ts = 72.503189
current_seq = 1299
count = 178
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.503632
last_received_ts = 72.503189
last_seq = 1299
current_ts = 72.520294
current_received_ts = 72.519890
current_seq = 1300
count = 179
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.520294
last_received_ts = 72.519890
last_seq = 1300
current_ts = 72.536964
current_received_ts = 72.536568
current_seq = 1301
count = 180
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.536964
last_received_ts = 72.536568
last_seq = 1301
current_ts = 72.553627
current_received_ts = 72.553223
current_seq = 1302
count = 181
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.553627
last_received_ts = 72.553223
last_seq = 1302
current_ts = 72.570297
current_received_ts = 72.569908
current_seq = 1303
count = 182
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.570297
last_received_ts = 72.569908
last_seq = 1303
current_ts = 72.586975
current_received_ts = 72.586571
current_seq = 1304
count = 183
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.586975
last_received_ts = 72.586571
last_seq = 1304
current_ts = 72.603638
current_received_ts = 72.603264
current_seq = 1305
count = 184
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.603638
last_received_ts = 72.603264
last_seq = 1305
current_ts = 72.620316
current_received_ts = 72.619911
current_seq = 1306
count = 185
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.620316
last_received_ts = 72.619911
last_seq = 1306
current_ts = 72.636986
current_received_ts = 72.636581
current_seq = 1307
count = 186
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.636986
last_received_ts = 72.636581
last_seq = 1307
current_ts = 72.653656
current_received_ts = 72.653275
current_seq = 1308
count = 187
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.653656
last_received_ts = 72.653275
last_seq = 1308
current_ts = 72.670326
current_received_ts = 72.669922
current_seq = 1309
count = 188
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.670326
last_received_ts = 72.669922
last_seq = 1309
current_ts = 72.686989
current_received_ts = 72.686577
current_seq = 1310
count = 189
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 46us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.686989
last_received_ts = 72.686577
last_seq = 1310
current_ts = 72.703659
current_received_ts = 72.703278
current_seq = 1311
count = 190
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.703659
last_received_ts = 72.703278
last_seq = 1311
current_ts = 72.720329
current_received_ts = 72.719925
current_seq = 1312
count = 191
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 49us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.720329
last_received_ts = 72.719925
last_seq = 1312
current_ts = 72.737000
current_received_ts = 72.736595
current_seq = 1313
count = 192
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.737000
last_received_ts = 72.736595
last_seq = 1313
current_ts = 72.753654
current_received_ts = 72.753288
current_seq = 1314
count = 193
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.753654
last_received_ts = 72.753288
last_seq = 1314
current_ts = 72.770340
current_received_ts = 72.769943
current_seq = 1315
count = 194
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 77us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.770340
last_received_ts = 72.769943
last_seq = 1315
current_ts = 72.787010
current_received_ts = 72.786552
current_seq = 1316
count = 195
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.787010
last_received_ts = 72.786552
last_seq = 1316
current_ts = 72.803665
current_received_ts = 72.803268
current_seq = 1317
count = 196
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.803665
last_received_ts = 72.803268
last_seq = 1317
current_ts = 72.820343
current_received_ts = 72.819946
current_seq = 1318
count = 197
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 72us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.820343
last_received_ts = 72.819946
last_seq = 1318
current_ts = 72.837013
current_received_ts = 72.836609
current_seq = 1319
count = 198
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.837013
last_received_ts = 72.836609
last_seq = 1319
current_ts = 72.853676
current_received_ts = 72.853279
current_seq = 1320
count = 199
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 47us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.853676
last_received_ts = 72.853279
last_seq = 1320
current_ts = 72.870354
current_received_ts = 72.869957
current_seq = 1321
count = 200
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 69us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.870354
last_received_ts = 72.869957
last_seq = 1321
current_ts = 72.887024
current_received_ts = 72.886627
current_seq = 1322
count = 201
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.887024
last_received_ts = 72.886627
last_seq = 1322
current_ts = 72.903694
current_received_ts = 72.903206
current_seq = 1323
count = 202
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.903694
last_received_ts = 72.903206
last_seq = 1323
current_ts = 72.920364
current_received_ts = 72.919968
current_seq = 1324
count = 203
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 72us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.920364
last_received_ts = 72.919968
last_seq = 1324
current_ts = 72.937035
current_received_ts = 72.936638
current_seq = 1325
count = 204
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.937035
last_received_ts = 72.936638
last_seq = 1325
current_ts = 72.953705
current_received_ts = 72.953255
current_seq = 1326
count = 205
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 48us
(kms_flip:2228) DEBUG: name = flip
last_ts = 72.953705
last_received_ts = 72.953255
last_seq = 1326
current_ts = 72.970375
current_received_ts = 72.969978
current_seq = 1327
count = 206
seq_step = 1
(kms_flip:2228) DEBUG: Vblank took 632us
(kms_flip:2228) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2228) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2228) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2228) igt_core-INFO: Stack trace:
(kms_flip:2228) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2228) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2228) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2228) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2228) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2228) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2228) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2228) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A3: FAIL (3.788s)
Dmesg
<6> [67.757499] Console: switching to colour dummy device 80x25
<6> [67.757581] [IGT] kms_flip: executing
<7> [67.774472] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [67.777272] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [67.832575] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [67.844590] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:DP-1]
<7> [67.844598] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:508:DP-1]
<7> [67.848850] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:DP-1] disconnected
<7> [67.849283] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:521:DP-2]
<7> [67.849288] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:521:DP-2]
<7> [67.853543] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:521:DP-2] disconnected
<7> [67.853906] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:530:HDMI-A-1]
<7> [67.853909] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:530:HDMI-A-1]
<7> [67.858225] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:530:HDMI-A-1] disconnected
<7> [67.858588] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:536:DP-3]
<7> [67.858592] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:536:DP-3]
<7> [67.862825] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:536:DP-3] disconnected
<7> [67.863203] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2]
<7> [67.863207] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2]
<7> [67.867457] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected
<7> [67.867836] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-4]
<7> [67.867841] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-4]
<7> [67.868121] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_D
<7> [67.889885] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [67.911042] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [67.932181] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [67.953309] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [67.974409] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [67.995530] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.016650] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.037784] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.058943] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.080082] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.101219] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.122353] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.143487] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.164625] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.185741] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.206909] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.228050] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.249183] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.270318] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.291456] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.312591] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.333725] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.354880] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.376020] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.397154] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.418284] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.439401] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.460535] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.481655] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.502836] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.523961] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.545078] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.545288] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DDI D/PHY D: Too many retries, giving up. First error: -110
<7> [68.545322] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-4] disconnected
<7> [68.545882] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:DP-1]
<7> [68.545886] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:508:DP-1]
<7> [68.550092] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:508:DP-1] disconnected
<7> [68.550109] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:521:DP-2]
<7> [68.550113] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:521:DP-2]
<7> [68.554313] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:521:DP-2] disconnected
<7> [68.554326] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:530:HDMI-A-1]
<7> [68.554330] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:530:HDMI-A-1]
<7> [68.558545] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:530:HDMI-A-1] disconnected
<7> [68.558559] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:536:DP-3]
<7> [68.558562] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:536:DP-3]
<7> [68.562752] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:536:DP-3] disconnected
<7> [68.562765] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2]
<7> [68.562768] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:544:HDMI-A-2]
<7> [68.567035] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:544:HDMI-A-2] disconnected
<7> [68.567048] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-4]
<7> [68.567051] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:548:DP-4]
<7> [68.588146] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.609241] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.630357] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.651485] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.672601] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.693765] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.714901] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.736015] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.757146] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.778272] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.799401] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.820537] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.841652] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.862804] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.883940] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.905052] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.926173] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.947302] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.968417] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [68.989548] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.010702] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.031823] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.052953] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.074082] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.095197] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.116325] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.137451] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.158566] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.179725] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.200848] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.221979] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.243096] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX D/DDI D/PHY D: timeout (status 0x7d40023f)
<7> [69.243302] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX D/DDI D/PHY D: Too many retries, giving up. First error: -110
<7> [69.243334] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:548:DP-4] disconnected
<7> [69.243351] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:HDMI-A-3]
<7> [69.243355] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [69.267654] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:556:HDMI-A-3] ELD monitor FH-DP4K
<7> [69.267660] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:556:HDMI-A-3] ELD size 36, SAD count 2
<7> [69.267841] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [69.268021] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK on first message, retry
<7> [69.268350] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpd] NAK for addr: 0040 w(1)
<7> [69.268516] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [69.268530] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [69.268688] 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> [69.269425] 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> [69.269433] 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> [69.269438] 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> [69.269444] 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> [69.269449] 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> [69.269455] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:556:HDMI-A-3] probed modes:
<7> [69.269458] 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> [69.269461] 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> [69.269464] 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> [69.269466] 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> [69.269469] 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> [69.269472] 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> [69.269474] 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> [69.269477] 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> [69.269480] 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> [69.269482] 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> [69.269485] 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> [69.269488] 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> [69.269490] 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> [69.269493] 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> [69.269496] 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> [69.269498] 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> [69.269501] 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> [69.269504] 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> [69.269506] 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> [69.269509] 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> [69.269512] 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> [69.269514] 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> [69.269517] 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> [69.269519] 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> [69.269522] 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> [69.269525] 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
<6> [69.274469] [IGT] kms_flip: starting subtest flip-vs-expired-vblank-interruptible
<6> [69.280824] [IGT] kms_flip: starting dynamic subtest A-HDMI-A3
<7> [69.281257] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562]
<7> [69.281411] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:563]
<7> [69.281703] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.282331] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.328893] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_D
<7> [69.365245] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.365598] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.371864] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.372193] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.391539] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.391940] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [69.398121] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [69.398396] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [69.398610] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [69.398867] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [69.399050] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [69.399232] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [69.399413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [69.399593] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [69.399799] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [69.400022] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [69.400202] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [69.400382] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [69.400561] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [69.400740] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [69.401071] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [69.401253] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [69.401433] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [69.401613] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [69.401994] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [69.402184] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [69.402366] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [69.402547] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [69.402728] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [69.402914] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [69.403096] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [69.403275] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [69.403456] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [69.403637] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [69.403915] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [69.404100] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [69.404280] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [69.404460] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [69.404641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [69.404861] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [69.405043] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [69.405223] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [69.405404] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [69.405584] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [69.405789] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [69.406009] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [69.406182] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [69.406380] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [69.406551] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [69.406722] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.406933] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.407106] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.407278] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.407468] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [69.407647] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<7> [69.407871] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [69.408066] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [69.408284] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [69.408464] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [69.408641] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [69.408859] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [69.409074] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [69.409253] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [69.409432] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [69.409611] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [69.409830] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [69.410010] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [69.423932] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [69.440923] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [69.441452] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [69.441687] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [69.441896] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [69.442078] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [69.442255] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [69.442432] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [69.442608] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [69.442790] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [69.442967] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [69.443143] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [69.443319] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [69.443495] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [69.443672] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [69.443889] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [69.444065] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [69.444242] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [69.444417] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [69.444617] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [69.444836] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [69.445013] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [69.445190] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [69.445367] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [69.445658] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [69.445988] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_A
<7> [69.446413] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [69.446646] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [69.447645] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [69.448057] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [69.448208] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [69.448368] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [69.448399] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:556:HDMI-A-3]
<7> [69.448477] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [69.448683] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [69.448894] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [69.449076] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:151:pipe A]
<7> [69.449246] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [69.449427] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [69.449608] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [69.449855] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [69.450035] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [69.450216] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [69.450396] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [69.450581] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [69.450789] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [69.450986] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [69.451167] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [69.451348] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [69.451528] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [69.451708] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [69.451923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [69.452103] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [69.452283] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [69.452462] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [69.452642] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [69.452905] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [69.453085] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [69.453265] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [69.453445] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [69.453625] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [69.453843] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [69.454024] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [69.454204] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [69.454384] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [69.454564] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [69.454748] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [69.454959] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [69.455139] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [69.455319] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [69.455499] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [69.455679] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [69.455923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [69.456103] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [69.456283] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [69.456487] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [69.456660] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [69.456896] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [69.457068] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [69.457240] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.457410] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.457581] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.457758] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35: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> [69.457978] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [69.458157] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [69.458334] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [69.458527] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [69.458719] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [69.458936] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [69.459153] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [69.459333] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [69.459513] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [69.459693] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [69.459879] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [69.460057] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [69.460236] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [69.460415] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [69.460593] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [69.460777] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [69.460955] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [69.461135] 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> [69.461314] 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> [69.461493] 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> [69.461672] 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> [69.461857] 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> [69.462038] 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> [69.462218] 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> [69.462398] 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> [69.462578] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [69.462763] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [69.463011] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [69.463190] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [69.463368] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [69.463547] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [69.463725] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [69.463938] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [69.464117] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [69.464296] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [69.464475] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [69.464654] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.464868] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.465077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.465256] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [69.465435] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [69.465613] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.465832] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.465869] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [69.466040] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [69.466125] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [69.466219] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [69.466310] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [69.466396] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:562] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [69.466576] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [69.466777] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [69.466971] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [69.467151] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [69.467330] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [69.467509] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [69.467687] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [69.469369] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [69.469692] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [69.470104] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [69.470337] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [69.470542] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [69.470719] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [69.470932] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [69.471109] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [69.471286] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [69.471462] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [69.471638] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [69.471818] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [69.471995] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [69.472172] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [69.472348] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [69.472523] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [69.472699] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [69.472912] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [69.473089] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [69.473264] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [69.473440] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [69.473615] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [69.473827] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [69.474004] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [69.474182] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [69.474574] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [69.474919] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [69.493875] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [69.494088] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [69.510803] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [69.511145] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<6> [73.069439] [IGT] kms_flip: finished subtest A-HDMI-A3, FAIL
Created at 2026-05-13 06:07:13