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

Result: Fail

git-log-oneline i915_display_info21 igt_runner21 runtimes21 results21.json results21-i915-load.json guc_logs21.tar i915_display_info_post_exec21 boot21 dmesg21

DetailValue
Duration 7.22 seconds
Hostname 
shard-dg1-15
Igt-Version 
IGT-Version: 2.4-gd84c66567 (x86_64) (Linux: 7.0.0-CI_DRM_18335-g45975e225ecb+ x86_64)
Out 
Starting dynamic subtest: D-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:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __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 D-HDMI-A1: FAIL (7.222s)
Subtest flip-vs-expired-vblank: FAIL (30.409s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2061) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2061) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.063545
last_received_ts = 105.063141
last_seq = 280
current_ts = 105.080215
current_received_ts = 105.079803
current_seq = 281
count = 277
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.080215
last_received_ts = 105.079803
last_seq = 281
current_ts = 105.096886
current_received_ts = 105.096481
current_seq = 282
count = 278
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.096886
last_received_ts = 105.096481
last_seq = 282
current_ts = 105.113548
current_received_ts = 105.113144
current_seq = 283
count = 279
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.113548
last_received_ts = 105.113144
last_seq = 283
current_ts = 105.130219
current_received_ts = 105.129814
current_seq = 284
count = 280
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.130219
last_received_ts = 105.129814
last_seq = 284
current_ts = 105.146889
current_received_ts = 105.146484
current_seq = 285
count = 281
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.146889
last_received_ts = 105.146484
last_seq = 285
current_ts = 105.163559
current_received_ts = 105.163155
current_seq = 286
count = 282
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.163559
last_received_ts = 105.163155
last_seq = 286
current_ts = 105.180222
current_received_ts = 105.179817
current_seq = 287
count = 283
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.180222
last_received_ts = 105.179817
last_seq = 287
current_ts = 105.196892
current_received_ts = 105.196487
current_seq = 288
count = 284
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.196892
last_received_ts = 105.196487
last_seq = 288
current_ts = 105.213562
current_received_ts = 105.213158
current_seq = 289
count = 285
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.213562
last_received_ts = 105.213158
last_seq = 289
current_ts = 105.230232
current_received_ts = 105.229820
current_seq = 290
count = 286
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.230232
last_received_ts = 105.229820
last_seq = 290
current_ts = 105.246895
current_received_ts = 105.246483
current_seq = 291
count = 287
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.246895
last_received_ts = 105.246483
last_seq = 291
current_ts = 105.263565
current_received_ts = 105.263161
current_seq = 292
count = 288
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.263565
last_received_ts = 105.263161
last_seq = 292
current_ts = 105.280235
current_received_ts = 105.279823
current_seq = 293
count = 289
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.280235
last_received_ts = 105.279823
last_seq = 293
current_ts = 105.296906
current_received_ts = 105.296501
current_seq = 294
count = 290
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.296906
last_received_ts = 105.296501
last_seq = 294
current_ts = 105.313568
current_received_ts = 105.313164
current_seq = 295
count = 291
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.313568
last_received_ts = 105.313164
last_seq = 295
current_ts = 105.330238
current_received_ts = 105.329826
current_seq = 296
count = 292
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.330238
last_received_ts = 105.329826
last_seq = 296
current_ts = 105.346909
current_received_ts = 105.346497
current_seq = 297
count = 293
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.346909
last_received_ts = 105.346497
last_seq = 297
current_ts = 105.363579
current_received_ts = 105.363174
current_seq = 298
count = 294
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.363579
last_received_ts = 105.363174
last_seq = 298
current_ts = 105.380241
current_received_ts = 105.379837
current_seq = 299
count = 295
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.380241
last_received_ts = 105.379837
last_seq = 299
current_ts = 105.396912
current_received_ts = 105.396507
current_seq = 300
count = 296
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.396912
last_received_ts = 105.396507
last_seq = 300
current_ts = 105.413582
current_received_ts = 105.413177
current_seq = 301
count = 297
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.413582
last_received_ts = 105.413177
last_seq = 301
current_ts = 105.430244
current_received_ts = 105.429840
current_seq = 302
count = 298
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.430244
last_received_ts = 105.429840
last_seq = 302
current_ts = 105.446915
current_received_ts = 105.446510
current_seq = 303
count = 299
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.446915
last_received_ts = 105.446510
last_seq = 303
current_ts = 105.463585
current_received_ts = 105.463181
current_seq = 304
count = 300
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.463585
last_received_ts = 105.463181
last_seq = 304
current_ts = 105.480255
current_received_ts = 105.479843
current_seq = 305
count = 301
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.480255
last_received_ts = 105.479843
last_seq = 305
current_ts = 105.496918
current_received_ts = 105.496513
current_seq = 306
count = 302
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.496918
last_received_ts = 105.496513
last_seq = 306
current_ts = 105.513588
current_received_ts = 105.513184
current_seq = 307
count = 303
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.513588
last_received_ts = 105.513184
last_seq = 307
current_ts = 105.530258
current_received_ts = 105.529854
current_seq = 308
count = 304
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.530258
last_received_ts = 105.529854
last_seq = 308
current_ts = 105.546928
current_received_ts = 105.546524
current_seq = 309
count = 305
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.546928
last_received_ts = 105.546524
last_seq = 309
current_ts = 105.563591
current_received_ts = 105.563179
current_seq = 310
count = 306
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.563591
last_received_ts = 105.563179
last_seq = 310
current_ts = 105.580261
current_received_ts = 105.579857
current_seq = 311
count = 307
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.580261
last_received_ts = 105.579857
last_seq = 311
current_ts = 105.596931
current_received_ts = 105.596527
current_seq = 312
count = 308
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.596931
last_received_ts = 105.596527
last_seq = 312
current_ts = 105.613602
current_received_ts = 105.613190
current_seq = 313
count = 309
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.613602
last_received_ts = 105.613190
last_seq = 313
current_ts = 105.630264
current_received_ts = 105.629860
current_seq = 314
count = 310
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.630264
last_received_ts = 105.629860
last_seq = 314
current_ts = 105.646935
current_received_ts = 105.646538
current_seq = 315
count = 311
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.646935
last_received_ts = 105.646538
last_seq = 315
current_ts = 105.663605
current_received_ts = 105.663193
current_seq = 316
count = 312
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.663605
last_received_ts = 105.663193
last_seq = 316
current_ts = 105.680267
current_received_ts = 105.679871
current_seq = 317
count = 313
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.680267
last_received_ts = 105.679871
last_seq = 317
current_ts = 105.696938
current_received_ts = 105.696533
current_seq = 318
count = 314
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.696938
last_received_ts = 105.696533
last_seq = 318
current_ts = 105.713608
current_received_ts = 105.713203
current_seq = 319
count = 315
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.713608
last_received_ts = 105.713203
last_seq = 319
current_ts = 105.730278
current_received_ts = 105.729874
current_seq = 320
count = 316
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.730278
last_received_ts = 105.729874
last_seq = 320
current_ts = 105.746941
current_received_ts = 105.746544
current_seq = 321
count = 317
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.746941
last_received_ts = 105.746544
last_seq = 321
current_ts = 105.763611
current_received_ts = 105.763199
current_seq = 322
count = 318
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.763611
last_received_ts = 105.763199
last_seq = 322
current_ts = 105.780281
current_received_ts = 105.779877
current_seq = 323
count = 319
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.780281
last_received_ts = 105.779877
last_seq = 323
current_ts = 105.796951
current_received_ts = 105.796547
current_seq = 324
count = 320
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.796951
last_received_ts = 105.796547
last_seq = 324
current_ts = 105.813614
current_received_ts = 105.813210
current_seq = 325
count = 321
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.813614
last_received_ts = 105.813210
last_seq = 325
current_ts = 105.830284
current_received_ts = 105.829880
current_seq = 326
count = 322
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.830284
last_received_ts = 105.829880
last_seq = 326
current_ts = 105.846954
current_received_ts = 105.846542
current_seq = 327
count = 323
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.846954
last_received_ts = 105.846542
last_seq = 327
current_ts = 105.863625
current_received_ts = 105.863213
current_seq = 328
count = 324
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.863625
last_received_ts = 105.863213
last_seq = 328
current_ts = 105.880287
current_received_ts = 105.879883
current_seq = 329
count = 325
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.880287
last_received_ts = 105.879883
last_seq = 329
current_ts = 105.896957
current_received_ts = 105.896553
current_seq = 330
count = 326
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.896957
last_received_ts = 105.896553
last_seq = 330
current_ts = 105.913628
current_received_ts = 105.913216
current_seq = 331
count = 327
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.913628
last_received_ts = 105.913216
last_seq = 331
current_ts = 105.930290
current_received_ts = 105.929893
current_seq = 332
count = 328
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.930290
last_received_ts = 105.929893
last_seq = 332
current_ts = 105.946960
current_received_ts = 105.946548
current_seq = 333
count = 329
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.946960
last_received_ts = 105.946548
last_seq = 333
current_ts = 105.963631
current_received_ts = 105.963219
current_seq = 334
count = 330
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.963631
last_received_ts = 105.963219
last_seq = 334
current_ts = 105.980301
current_received_ts = 105.979897
current_seq = 335
count = 331
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.980301
last_received_ts = 105.979897
last_seq = 335
current_ts = 105.996956
current_received_ts = 105.996567
current_seq = 336
count = 332
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 105.996956
last_received_ts = 105.996567
last_seq = 336
current_ts = 106.013634
current_received_ts = 106.013229
current_seq = 337
count = 333
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.013634
last_received_ts = 106.013229
last_seq = 337
current_ts = 106.030304
current_received_ts = 106.029900
current_seq = 338
count = 334
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.030304
last_received_ts = 106.029900
last_seq = 338
current_ts = 106.046974
current_received_ts = 106.046570
current_seq = 339
count = 335
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.046974
last_received_ts = 106.046570
last_seq = 339
current_ts = 106.063637
current_received_ts = 106.063232
current_seq = 340
count = 336
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.063637
last_received_ts = 106.063232
last_seq = 340
current_ts = 106.080307
current_received_ts = 106.079903
current_seq = 341
count = 337
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.080307
last_received_ts = 106.079903
last_seq = 341
current_ts = 106.096977
current_received_ts = 106.096573
current_seq = 342
count = 338
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.096977
last_received_ts = 106.096573
last_seq = 342
current_ts = 106.113640
current_received_ts = 106.113243
current_seq = 343
count = 339
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.113640
last_received_ts = 106.113243
last_seq = 343
current_ts = 106.130310
current_received_ts = 106.129906
current_seq = 344
count = 340
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 102us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.130310
last_received_ts = 106.129906
last_seq = 344
current_ts = 106.146980
current_received_ts = 106.146576
current_seq = 345
count = 341
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.146980
last_received_ts = 106.146576
last_seq = 345
current_ts = 106.163643
current_received_ts = 106.163246
current_seq = 346
count = 342
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.163643
last_received_ts = 106.163246
last_seq = 346
current_ts = 106.180321
current_received_ts = 106.179916
current_seq = 347
count = 343
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 93us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.180321
last_received_ts = 106.179916
last_seq = 347
current_ts = 106.196983
current_received_ts = 106.196579
current_seq = 348
count = 344
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.196983
last_received_ts = 106.196579
last_seq = 348
current_ts = 106.213646
current_received_ts = 106.213257
current_seq = 349
count = 345
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.213646
last_received_ts = 106.213257
last_seq = 349
current_ts = 106.230324
current_received_ts = 106.229912
current_seq = 350
count = 346
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 100us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.230324
last_received_ts = 106.229912
last_seq = 350
current_ts = 106.246986
current_received_ts = 106.246582
current_seq = 351
count = 347
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.246986
last_received_ts = 106.246582
last_seq = 351
current_ts = 106.263649
current_received_ts = 106.263260
current_seq = 352
count = 348
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.263649
last_received_ts = 106.263260
last_seq = 352
current_ts = 106.280327
current_received_ts = 106.279922
current_seq = 353
count = 349
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 98us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.280327
last_received_ts = 106.279922
last_seq = 353
current_ts = 106.296997
current_received_ts = 106.296585
current_seq = 354
count = 350
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.296997
last_received_ts = 106.296585
last_seq = 354
current_ts = 106.313660
current_received_ts = 106.313271
current_seq = 355
count = 351
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.313660
last_received_ts = 106.313271
last_seq = 355
current_ts = 106.330330
current_received_ts = 106.329918
current_seq = 356
count = 352
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 99us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.330330
last_received_ts = 106.329918
last_seq = 356
current_ts = 106.347000
current_received_ts = 106.346588
current_seq = 357
count = 353
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.347000
last_received_ts = 106.346588
last_seq = 357
current_ts = 106.363663
current_received_ts = 106.363266
current_seq = 358
count = 354
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.363663
last_received_ts = 106.363266
last_seq = 358
current_ts = 106.380333
current_received_ts = 106.379929
current_seq = 359
count = 355
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 104us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.380333
last_received_ts = 106.379929
last_seq = 359
current_ts = 106.397003
current_received_ts = 106.396591
current_seq = 360
count = 356
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.397003
last_received_ts = 106.396591
last_seq = 360
current_ts = 106.413666
current_received_ts = 106.413277
current_seq = 361
count = 357
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.413666
last_received_ts = 106.413277
last_seq = 361
current_ts = 106.430344
current_received_ts = 106.429932
current_seq = 362
count = 358
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 112us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.430344
last_received_ts = 106.429932
last_seq = 362
current_ts = 106.447006
current_received_ts = 106.446609
current_seq = 363
count = 359
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.447006
last_received_ts = 106.446609
last_seq = 363
current_ts = 106.463669
current_received_ts = 106.463272
current_seq = 364
count = 360
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.463669
last_received_ts = 106.463272
last_seq = 364
current_ts = 106.480347
current_received_ts = 106.479935
current_seq = 365
count = 361
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 100us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.480347
last_received_ts = 106.479935
last_seq = 365
current_ts = 106.497009
current_received_ts = 106.496605
current_seq = 366
count = 362
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.497009
last_received_ts = 106.496605
last_seq = 366
current_ts = 106.513680
current_received_ts = 106.513283
current_seq = 367
count = 363
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.513680
last_received_ts = 106.513283
last_seq = 367
current_ts = 106.530350
current_received_ts = 106.529938
current_seq = 368
count = 364
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 97us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.530350
last_received_ts = 106.529938
last_seq = 368
current_ts = 106.547020
current_received_ts = 106.546608
current_seq = 369
count = 365
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.547020
last_received_ts = 106.546608
last_seq = 369
current_ts = 106.563683
current_received_ts = 106.563293
current_seq = 370
count = 366
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.563683
last_received_ts = 106.563293
last_seq = 370
current_ts = 106.580353
current_received_ts = 106.579948
current_seq = 371
count = 367
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 100us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.580353
last_received_ts = 106.579948
last_seq = 371
current_ts = 106.597023
current_received_ts = 106.596611
current_seq = 372
count = 368
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.597023
last_received_ts = 106.596611
last_seq = 372
current_ts = 106.613686
current_received_ts = 106.613297
current_seq = 373
count = 369
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.613686
last_received_ts = 106.613297
last_seq = 373
current_ts = 106.630348
current_received_ts = 106.629951
current_seq = 374
count = 370
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 97us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.630348
last_received_ts = 106.629951
last_seq = 374
current_ts = 106.647026
current_received_ts = 106.646622
current_seq = 375
count = 371
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.647026
last_received_ts = 106.646622
last_seq = 375
current_ts = 106.663696
current_received_ts = 106.663284
current_seq = 376
count = 372
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.663696
last_received_ts = 106.663284
last_seq = 376
current_ts = 106.680367
current_received_ts = 106.679955
current_seq = 377
count = 373
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 98us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.680367
last_received_ts = 106.679955
last_seq = 377
current_ts = 106.697029
current_received_ts = 106.696617
current_seq = 378
count = 374
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.697029
last_received_ts = 106.696617
last_seq = 378
current_ts = 106.713699
current_received_ts = 106.713287
current_seq = 379
count = 375
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.713699
last_received_ts = 106.713287
last_seq = 379
current_ts = 106.730370
current_received_ts = 106.729958
current_seq = 380
count = 376
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 94us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.730370
last_received_ts = 106.729958
last_seq = 380
current_ts = 106.747032
current_received_ts = 106.746628
current_seq = 381
count = 377
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.747032
last_received_ts = 106.746628
last_seq = 381
current_ts = 106.763702
current_received_ts = 106.763290
current_seq = 382
count = 378
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 56us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.763702
last_received_ts = 106.763290
last_seq = 382
current_ts = 106.780373
current_received_ts = 106.779961
current_seq = 383
count = 379
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 93us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.780373
last_received_ts = 106.779961
last_seq = 383
current_ts = 106.797035
current_received_ts = 106.796638
current_seq = 384
count = 380
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.797035
last_received_ts = 106.796638
last_seq = 384
current_ts = 106.813698
current_received_ts = 106.813309
current_seq = 385
count = 381
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.813698
last_received_ts = 106.813309
last_seq = 385
current_ts = 106.830376
current_received_ts = 106.829971
current_seq = 386
count = 382
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.830376
last_received_ts = 106.829971
last_seq = 386
current_ts = 106.847046
current_received_ts = 106.846642
current_seq = 387
count = 383
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.847046
last_received_ts = 106.846642
last_seq = 387
current_ts = 106.863708
current_received_ts = 106.863297
current_seq = 388
count = 384
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.863708
last_received_ts = 106.863297
last_seq = 388
current_ts = 106.880379
current_received_ts = 106.879967
current_seq = 389
count = 385
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.880379
last_received_ts = 106.879967
last_seq = 389
current_ts = 106.897049
current_received_ts = 106.896637
current_seq = 390
count = 386
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.897049
last_received_ts = 106.896637
last_seq = 390
current_ts = 106.913712
current_received_ts = 106.913307
current_seq = 391
count = 387
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.913712
last_received_ts = 106.913307
last_seq = 391
current_ts = 106.930382
current_received_ts = 106.929970
current_seq = 392
count = 388
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 58us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.930382
last_received_ts = 106.929970
last_seq = 392
current_ts = 106.947052
current_received_ts = 106.946640
current_seq = 393
count = 389
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.947052
last_received_ts = 106.946640
last_seq = 393
current_ts = 106.963722
current_received_ts = 106.963310
current_seq = 394
count = 390
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.963722
last_received_ts = 106.963310
last_seq = 394
current_ts = 106.980392
current_received_ts = 106.979980
current_seq = 395
count = 391
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.980392
last_received_ts = 106.979980
last_seq = 395
current_ts = 106.997055
current_received_ts = 106.996643
current_seq = 396
count = 392
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 106.997055
last_received_ts = 106.996643
last_seq = 396
current_ts = 107.013725
current_received_ts = 107.013313
current_seq = 397
count = 393
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 107.013725
last_received_ts = 107.013313
last_seq = 397
current_ts = 107.030396
current_received_ts = 107.029984
current_seq = 398
count = 394
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 107.030396
last_received_ts = 107.029984
last_seq = 398
current_ts = 107.047058
current_received_ts = 107.046654
current_seq = 399
count = 395
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 107.047058
last_received_ts = 107.046654
last_seq = 399
current_ts = 107.063728
current_received_ts = 107.063316
current_seq = 400
count = 396
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 107.063728
last_received_ts = 107.063316
last_seq = 400
current_ts = 107.080399
current_received_ts = 107.079987
current_seq = 401
count = 397
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 57us
(kms_flip:2061) DEBUG: name = flip
last_ts = 107.080399
last_received_ts = 107.079987
last_seq = 401
current_ts = 107.097069
current_received_ts = 107.096657
current_seq = 402
count = 398
seq_step = 1
(kms_flip:2061) DEBUG: Vblank took 867us
(kms_flip:2061) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2061) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2061) igt_core-INFO: Stack trace:
(kms_flip:2061) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2061) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2061) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2061) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:2061) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2061) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2061) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2061) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A1: FAIL (7.222s)
Subtest flip-vs-expired-vblank: FAIL (30.409s)
(kms_flip:2061) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2061) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2061) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2061) igt_kms-CRITICAL: DPMS property not found on 600
Dmesg
<6> [100.124837] [IGT] kms_flip: starting dynamic subtest D-HDMI-A1
<7> [100.125237] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:634]
<7> [100.125369] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:638]
<7> [100.125676] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.126172] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.145808] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.146150] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.155916] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.156237] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.175722] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.176049] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [100.203771] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [100.203962] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [100.204124] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [100.204222] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [100.204393] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [100.204543] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [100.204704] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [100.204853] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [100.205002] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [100.205151] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [100.205299] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [100.205447] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [100.205595] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [100.205785] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [100.205934] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [100.206083] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [100.206232] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [100.206381] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [100.206529] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [100.206718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [100.206867] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [100.207016] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [100.207165] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [100.207313] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [100.207462] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [100.207955] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [100.208110] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [100.208260] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [100.208409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [100.208557] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [100.208745] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [100.208894] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [100.209042] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [100.209190] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [100.209338] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [100.209486] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [100.209712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [100.209861] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [100.210009] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [100.210158] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe B] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [100.210307] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:447:pipe B] fastset requirement not met, forcing full modeset
<7> [100.210456] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:447:pipe B] releasing DPLL 0
<7> [100.210664] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [100.210811] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:447:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [100.210999] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:441:cursor B] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [100.211166] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:447:pipe B] min cdclk: 74250 kHz -> 0 kHz
<7> [100.211317] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:447:pipe B] enable: no [modeset]
<7> [100.211466] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:311:plane 1B] fb: [NOFB], visible: no
<7> [100.211640] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:341:plane 2B] fb: [NOFB], visible: no
<7> [100.211803] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 3B] fb: [NOFB], visible: no
<7> [100.211949] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:401:plane 4B] fb: [NOFB], visible: no
<7> [100.212097] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:411:plane 5B] fb: [NOFB], visible: no
<7> [100.212244] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:421:plane 6B] fb: [NOFB], visible: no
<7> [100.212391] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:431:plane 7B] fb: [NOFB], visible: no
<7> [100.212538] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:441:cursor B] fb: [NOFB], visible: no
<7> [100.212933] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [100.225710] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [100.225899] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:447:pipe B]
<7> [100.226081] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [100.226251] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [100.226420] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [100.226569] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [100.226763] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [100.226910] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [100.227058] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [100.227206] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [100.227353] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [100.227501] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [100.227691] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [100.227839] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [100.227987] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [100.228134] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [100.228282] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [100.228430] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [100.228577] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [100.228763] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [100.229034] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [100.229238] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [100.229425] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [100.229645] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:447:pipe B]
<7> [100.230064] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [100.230260] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [100.230299] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:600:HDMI-A-1]
<7> [100.230386] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [100.230543] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [100.230724] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [100.230875] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:585:pipe D]
<7> [100.231018] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [100.231168] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [100.231317] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [100.231466] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [100.231645] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [100.231808] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [100.231956] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [100.232105] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [100.232252] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [100.232401] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [100.232548] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [100.232736] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [100.232885] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [100.233033] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [100.233181] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [100.233330] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [100.233478] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [100.233653] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [100.233816] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [100.233964] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [100.234112] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [100.234261] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [100.234409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [100.234558] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [100.234744] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [100.234893] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [100.235042] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [100.235189] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [100.235337] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [100.235485] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [100.235684] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [100.235833] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [100.235981] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [100.236130] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in dpll_hw_state
<7> [100.236278] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [100.236427] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [100.236576] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [100.236766] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [100.236916] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [100.237065] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [100.237214] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [100.237363] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [100.237512] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [100.237728] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [100.237874] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [100.238064] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [100.238209] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [100.238354] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [100.238499] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [100.238721] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [100.238867] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [100.239031] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 594000 num active planes 1
<7> [100.239180] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [100.239327] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [100.239491] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 74250 kHz
<7> [100.239698] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [100.239849] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [100.239999] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [100.240148] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [100.240296] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [100.240444] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [100.240591] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [100.240782] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [100.240929] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [100.241077] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [100.241225] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [100.241373] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [100.241520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [100.241709] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [100.241857] 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> [100.242005] 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> [100.242154] 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> [100.242302] 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> [100.242452] 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> [100.242626] 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> [100.242791] 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> [100.242939] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [100.243087] 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> [100.243235] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [100.243384] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [100.243532] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [100.243719] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [100.243867] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [100.244017] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [100.244165] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [100.244313] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [100.244461] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.244643] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.244805] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.244952] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [100.245100] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [100.245247] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.245395] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.245543] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [100.245733] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [100.245881] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:634] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [100.246031] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [100.246179] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [100.246328] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [100.246476] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [100.246664] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [100.246812] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [100.246959] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [100.247106] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [100.247254] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [100.247656] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [100.247855] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [100.248010] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [100.249630] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [100.251063] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [100.252770] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [100.252936] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [100.253112] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [100.253285] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [100.253478] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [100.253671] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [100.253819] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [100.253967] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [100.254114] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [100.254262] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [100.254410] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [100.254557] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [100.254745] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [100.254892] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [100.255039] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [100.255187] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [100.255334] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [100.255482] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [100.255667] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [100.255815] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [100.256040] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [100.256236] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [100.256387] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [100.256592] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [100.256858] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [100.274987] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [100.275265] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<6> [107.347419] [IGT] kms_flip: finished subtest D-HDMI-A1, FAIL
<6> [107.351985] [IGT] kms_flip: finished subtest flip-vs-expired-vblank, FAIL
<7> [107.361848] i915 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:634] from all active usage due to RMFB ioctl
<7> [107.362150] i915 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:449:plane 1D] because [FB:634] is removed
<7> [107.362214] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [107.362395] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [107.362537] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [107.362680] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [107.362822] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [107.363039] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [107.363215] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [107.363358] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [107.375940] [IGT] kms_flip: exiting, ret=98
<7> [107.391906] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:600:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [107.392266] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [107.392408] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [107.392571] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI A/PHY A] [CRTC:171:pipe A]
<7> [107.392712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [107.392875] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [107.393064] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [107.393242] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [107.393388] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [107.393535] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [107.393681] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [107.393827] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [107.393973] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [107.394296] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [107.394457] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [107.394603] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [107.394748] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [107.394893] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [107.395592] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [107.395770] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [107.395931] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [107.396251] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [107.396398] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [107.396543] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [107.396688] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [107.396834] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [107.396980] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [107.397568] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [107.397718] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [107.397864] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [107.398037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [107.398244] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [107.398391] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [107.398537] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [107.398683] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [107.398846] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [107.399019] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [107.399225] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [107.399371] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [107.399516] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [107.399661] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [107.399807] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [107.399952] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [107.400162] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [107.400324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [107.400510] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [107.400655] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [107.400800] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [107.400945] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [107.401146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [107.401322] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [107.401468] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [107.401613] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [107.401774] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [107.401924] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [107.402156] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [107.402331] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [107.402477] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [107.402622] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [107.402768] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [107.402913] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [107.403115] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [107.403304] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [107.403490] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [107.403635] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [107.403781] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [107.403927] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [107.404115] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [107.404264] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [107.404413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [107.404562] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [107.404712] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [107.404860] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [107.405101] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [107.405252] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [107.405402] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [107.405550] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [107.405699] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [107.405848] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [107.406026] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [107.406189] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [107.406383] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [107.406532] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [107.406683] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [107.406829] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [107.406974] 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> [107.407203] 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 -> 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [107.407350] 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 -> 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [107.407497] 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 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [107.407646] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [107.407813] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [107.407963] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [107.408149] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [107.408317] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [107.408466] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 74250 kHz -> 0 kHz
<7> [107.408635] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [107.408786] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [107.408940] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [107.409131] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [107.409281] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [107.409430] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [107.409578] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [107.409727] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [107.409875] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [107.410074] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [107.410223] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [107.410370] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [107.410518] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [107.410667] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [107.410815] 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> [107.410963] 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> [107.411153] 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> [107.411303] 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> [107.411453] 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> [107.411602] 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> [107.411752] 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> [107.411901] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [107.412091] 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> [107.412240] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [107.412388] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [107.412537] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [107.412686] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [107.412834] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [107.412984] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [107.413181] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [107.413330] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [107.413478] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.413627] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.413775] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.413923] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [107.414111] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [107.414261] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.414408] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.414556] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [107.414704] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [107.414852] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [107.415029] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [107.415194] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [107.415343] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [107.415492] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [107.415640] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [107.415788] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [107.415936] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [107.416128] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [107.416277] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [107.416425] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [107.416574] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [107.416722] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [107.416870] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [107.417046] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [107.417208] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [107.417357] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [107.417506] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [107.417654] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [107.418382] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [107.425089] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [107.425281] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [107.425519] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [107.425691] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [107.425840] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [107.426021] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [107.426184] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [107.426332] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [107.426481] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [107.426629] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [107.426778] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [107.426926] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [107.427116] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [107.427264] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [107.427412] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [107.427561] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [107.427709] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [107.427857] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [107.428033] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [107.428454] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [107.428605] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [107.428811] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [107.429091] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [107.430276] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [107.430430] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [107.447284] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [107.447579] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [107.448591] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [107.448892] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [107.449279] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [107.449598] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [107.449788] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [107.450293] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-16 03:40:57