Results for igt@kms_flip@2x-flip-vs-expired-vblank@ac-hdmi-a1-hdmi-a2

Result: Fail

git-log-oneline i915_display_info14 igt_runner14 runtimes14 results14.json results14-i915-load.json guc_logs14.tar i915_display_info_post_exec14 boot14 dmesg14

DetailValue
Duration 8.99 seconds
Hostname 
shard-glk2
Igt-Version 
IGT-Version: 2.4-gfc0fba297 (x86_64) (Linux: 7.0.0-CI_DRM_18329-g4e3f5ea9aacc+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
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:2029 run_pair()
  #3 ../tests/kms_flip.c:2193 __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 AC-HDMI-A1-HDMI-A2: FAIL (8.987s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:2617) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2617) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.158661
last_received_ts = 484.158539
last_seq = 17354
current_ts = 484.175354
current_received_ts = 484.175201
current_seq = 17355
count = 348
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.175354
last_received_ts = 484.175201
last_seq = 17355
current_ts = 484.191986
current_received_ts = 484.191589
current_seq = 17356
count = 349
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.191986
last_received_ts = 484.191589
last_seq = 17356
current_ts = 484.208679
current_received_ts = 484.208557
current_seq = 17357
count = 350
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.208679
last_received_ts = 484.208557
last_seq = 17357
current_ts = 484.225342
current_received_ts = 484.225189
current_seq = 17358
count = 351
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.225342
last_received_ts = 484.225189
last_seq = 17358
current_ts = 484.242004
current_received_ts = 484.241608
current_seq = 17359
count = 352
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.242004
last_received_ts = 484.241608
last_seq = 17359
current_ts = 484.258667
current_received_ts = 484.258545
current_seq = 17360
count = 353
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.258667
last_received_ts = 484.258545
last_seq = 17360
current_ts = 484.275360
current_received_ts = 484.275208
current_seq = 17361
count = 354
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.275360
last_received_ts = 484.275208
last_seq = 17361
current_ts = 484.291992
current_received_ts = 484.291656
current_seq = 17362
count = 355
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.291992
last_received_ts = 484.291656
last_seq = 17362
current_ts = 484.308685
current_received_ts = 484.308563
current_seq = 17363
count = 356
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.308685
last_received_ts = 484.308563
last_seq = 17363
current_ts = 484.325348
current_received_ts = 484.325226
current_seq = 17364
count = 357
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.325348
last_received_ts = 484.325226
last_seq = 17364
current_ts = 484.342010
current_received_ts = 484.341644
current_seq = 17365
count = 358
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.342010
last_received_ts = 484.341644
last_seq = 17365
current_ts = 484.358673
current_received_ts = 484.358521
current_seq = 17366
count = 359
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.358673
last_received_ts = 484.358521
last_seq = 17366
current_ts = 484.375336
current_received_ts = 484.375183
current_seq = 17367
count = 360
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.375336
last_received_ts = 484.375183
last_seq = 17367
current_ts = 484.391998
current_received_ts = 484.391571
current_seq = 17368
count = 361
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.391998
last_received_ts = 484.391571
last_seq = 17368
current_ts = 484.408661
current_received_ts = 484.408539
current_seq = 17369
count = 362
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 19us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.408661
last_received_ts = 484.408539
last_seq = 17369
current_ts = 484.425323
current_received_ts = 484.424896
current_seq = 17370
count = 363
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.425323
last_received_ts = 484.424896
last_seq = 17370
current_ts = 484.442017
current_received_ts = 484.441620
current_seq = 17371
count = 364
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.442017
last_received_ts = 484.441620
last_seq = 17371
current_ts = 484.458679
current_received_ts = 484.458527
current_seq = 17372
count = 365
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.458679
last_received_ts = 484.458527
last_seq = 17372
current_ts = 484.475342
current_received_ts = 484.475037
current_seq = 17373
count = 366
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.475342
last_received_ts = 484.475037
last_seq = 17373
current_ts = 484.492004
current_received_ts = 484.491974
current_seq = 17374
count = 367
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.492004
last_received_ts = 484.491974
last_seq = 17374
current_ts = 484.508667
current_received_ts = 484.508514
current_seq = 17375
count = 368
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 19us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.508667
last_received_ts = 484.508514
last_seq = 17375
current_ts = 484.525330
current_received_ts = 484.525330
current_seq = 17376
count = 369
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.525330
last_received_ts = 484.525330
last_seq = 17376
current_ts = 484.541992
current_received_ts = 484.541656
current_seq = 17377
count = 370
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.541992
last_received_ts = 484.541656
last_seq = 17377
current_ts = 484.558685
current_received_ts = 484.558563
current_seq = 17378
count = 371
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.558685
last_received_ts = 484.558563
last_seq = 17378
current_ts = 484.575348
current_received_ts = 484.575287
current_seq = 17379
count = 372
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.575348
last_received_ts = 484.575287
last_seq = 17379
current_ts = 484.592010
current_received_ts = 484.591583
current_seq = 17380
count = 373
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.592010
last_received_ts = 484.591583
last_seq = 17380
current_ts = 484.608673
current_received_ts = 484.608551
current_seq = 17381
count = 374
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.608673
last_received_ts = 484.608551
last_seq = 17381
current_ts = 484.625336
current_received_ts = 484.625214
current_seq = 17382
count = 375
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.625336
last_received_ts = 484.625214
last_seq = 17382
current_ts = 484.641998
current_received_ts = 484.641602
current_seq = 17383
count = 376
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.641998
last_received_ts = 484.641602
last_seq = 17383
current_ts = 484.658661
current_received_ts = 484.658539
current_seq = 17384
count = 377
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.658661
last_received_ts = 484.658539
last_seq = 17384
current_ts = 484.675323
current_received_ts = 484.675262
current_seq = 17385
count = 378
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.675323
last_received_ts = 484.675262
last_seq = 17385
current_ts = 484.691986
current_received_ts = 484.691650
current_seq = 17386
count = 379
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.691986
last_received_ts = 484.691650
last_seq = 17386
current_ts = 484.708679
current_received_ts = 484.708527
current_seq = 17387
count = 380
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.708679
last_received_ts = 484.708527
last_seq = 17387
current_ts = 484.725342
current_received_ts = 484.725220
current_seq = 17388
count = 381
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.725342
last_received_ts = 484.725220
last_seq = 17388
current_ts = 484.742004
current_received_ts = 484.741669
current_seq = 17389
count = 382
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.742004
last_received_ts = 484.741669
last_seq = 17389
current_ts = 484.758667
current_received_ts = 484.758484
current_seq = 17390
count = 383
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.758667
last_received_ts = 484.758484
last_seq = 17390
current_ts = 484.775330
current_received_ts = 484.775146
current_seq = 17391
count = 384
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.775330
last_received_ts = 484.775146
last_seq = 17391
current_ts = 484.791992
current_received_ts = 484.791626
current_seq = 17392
count = 385
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.791992
last_received_ts = 484.791626
last_seq = 17392
current_ts = 484.808685
current_received_ts = 484.808533
current_seq = 17393
count = 386
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.808685
last_received_ts = 484.808533
last_seq = 17393
current_ts = 484.825348
current_received_ts = 484.825104
current_seq = 17394
count = 387
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 18us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.825348
last_received_ts = 484.825104
last_seq = 17394
current_ts = 484.842010
current_received_ts = 484.841553
current_seq = 17395
count = 388
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.842010
last_received_ts = 484.841553
last_seq = 17395
current_ts = 484.858673
current_received_ts = 484.858459
current_seq = 17396
count = 389
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.858673
last_received_ts = 484.858459
last_seq = 17396
current_ts = 484.875336
current_received_ts = 484.875244
current_seq = 17397
count = 390
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.875336
last_received_ts = 484.875244
last_seq = 17397
current_ts = 484.891998
current_received_ts = 484.891663
current_seq = 17398
count = 391
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.891998
last_received_ts = 484.891663
last_seq = 17398
current_ts = 484.908691
current_received_ts = 484.908569
current_seq = 17399
count = 392
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.908691
last_received_ts = 484.908569
last_seq = 17399
current_ts = 484.925323
current_received_ts = 484.925293
current_seq = 17400
count = 393
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.925323
last_received_ts = 484.925293
last_seq = 17400
current_ts = 484.942017
current_received_ts = 484.941803
current_seq = 17401
count = 394
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 18us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.942017
last_received_ts = 484.941803
last_seq = 17401
current_ts = 484.958679
current_received_ts = 484.958557
current_seq = 17402
count = 395
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.958679
last_received_ts = 484.958557
last_seq = 17402
current_ts = 484.975342
current_received_ts = 484.975220
current_seq = 17403
count = 396
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.975342
last_received_ts = 484.975220
last_seq = 17403
current_ts = 484.992004
current_received_ts = 484.991577
current_seq = 17404
count = 397
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 484.992004
last_received_ts = 484.991577
last_seq = 17404
current_ts = 485.008667
current_received_ts = 485.008545
current_seq = 17405
count = 398
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.008667
last_received_ts = 485.008545
last_seq = 17405
current_ts = 485.025330
current_received_ts = 485.025177
current_seq = 17406
count = 399
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 19us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.025330
last_received_ts = 485.025177
last_seq = 17406
current_ts = 485.041992
current_received_ts = 485.041656
current_seq = 17407
count = 400
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.041992
last_received_ts = 485.041656
last_seq = 17407
current_ts = 485.058655
current_received_ts = 485.058533
current_seq = 17408
count = 401
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.058655
last_received_ts = 485.058533
last_seq = 17408
current_ts = 485.075348
current_received_ts = 485.075226
current_seq = 17409
count = 402
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.075348
last_received_ts = 485.075226
last_seq = 17409
current_ts = 485.092010
current_received_ts = 485.091644
current_seq = 17410
count = 403
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.092010
last_received_ts = 485.091644
last_seq = 17410
current_ts = 485.108673
current_received_ts = 485.108521
current_seq = 17411
count = 404
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.108673
last_received_ts = 485.108521
last_seq = 17411
current_ts = 485.125336
current_received_ts = 485.125214
current_seq = 17412
count = 405
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.125336
last_received_ts = 485.125214
last_seq = 17412
current_ts = 485.141998
current_received_ts = 485.141541
current_seq = 17413
count = 406
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.141998
last_received_ts = 485.141541
last_seq = 17413
current_ts = 485.158661
current_received_ts = 485.158508
current_seq = 17414
count = 407
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.158661
last_received_ts = 485.158508
last_seq = 17414
current_ts = 485.175354
current_received_ts = 485.175262
current_seq = 17415
count = 408
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.175354
last_received_ts = 485.175262
last_seq = 17415
current_ts = 485.191986
current_received_ts = 485.191589
current_seq = 17416
count = 409
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.191986
last_received_ts = 485.191589
last_seq = 17416
current_ts = 485.208679
current_received_ts = 485.208466
current_seq = 17417
count = 410
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.208679
last_received_ts = 485.208466
last_seq = 17417
current_ts = 485.225342
current_received_ts = 485.225250
current_seq = 17418
count = 411
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.225342
last_received_ts = 485.225250
last_seq = 17418
current_ts = 485.242004
current_received_ts = 485.241608
current_seq = 17419
count = 412
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.242004
last_received_ts = 485.241608
last_seq = 17419
current_ts = 485.258667
current_received_ts = 485.258545
current_seq = 17420
count = 413
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.258667
last_received_ts = 485.258545
last_seq = 17420
current_ts = 485.275330
current_received_ts = 485.275269
current_seq = 17421
count = 414
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.275330
last_received_ts = 485.275269
last_seq = 17421
current_ts = 485.291992
current_received_ts = 485.291656
current_seq = 17422
count = 415
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.291992
last_received_ts = 485.291656
last_seq = 17422
current_ts = 485.308685
current_received_ts = 485.308563
current_seq = 17423
count = 416
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.308685
last_received_ts = 485.308563
last_seq = 17423
current_ts = 485.325348
current_received_ts = 485.325195
current_seq = 17424
count = 417
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.325348
last_received_ts = 485.325195
last_seq = 17424
current_ts = 485.342010
current_received_ts = 485.341614
current_seq = 17425
count = 418
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.342010
last_received_ts = 485.341614
last_seq = 17425
current_ts = 485.358673
current_received_ts = 485.358582
current_seq = 17426
count = 419
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.358673
last_received_ts = 485.358582
last_seq = 17426
current_ts = 485.375336
current_received_ts = 485.374969
current_seq = 17427
count = 420
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.375336
last_received_ts = 485.374969
last_seq = 17427
current_ts = 485.391998
current_received_ts = 485.391571
current_seq = 17428
count = 421
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.391998
last_received_ts = 485.391571
last_seq = 17428
current_ts = 485.408661
current_received_ts = 485.408569
current_seq = 17429
count = 422
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.408661
last_received_ts = 485.408569
last_seq = 17429
current_ts = 485.425323
current_received_ts = 485.425232
current_seq = 17430
count = 423
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.425323
last_received_ts = 485.425232
last_seq = 17430
current_ts = 485.441986
current_received_ts = 485.441620
current_seq = 17431
count = 424
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.441986
last_received_ts = 485.441620
last_seq = 17431
current_ts = 485.458679
current_received_ts = 485.458557
current_seq = 17432
count = 425
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.458679
last_received_ts = 485.458557
last_seq = 17432
current_ts = 485.475342
current_received_ts = 485.475250
current_seq = 17433
count = 426
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.475342
last_received_ts = 485.475250
last_seq = 17433
current_ts = 485.492004
current_received_ts = 485.491638
current_seq = 17434
count = 427
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.492004
last_received_ts = 485.491638
last_seq = 17434
current_ts = 485.508667
current_received_ts = 485.508545
current_seq = 17435
count = 428
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.508667
last_received_ts = 485.508545
last_seq = 17435
current_ts = 485.525330
current_received_ts = 485.525238
current_seq = 17436
count = 429
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.525330
last_received_ts = 485.525238
last_seq = 17436
current_ts = 485.541992
current_received_ts = 485.541565
current_seq = 17437
count = 430
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.541992
last_received_ts = 485.541565
last_seq = 17437
current_ts = 485.558655
current_received_ts = 485.558563
current_seq = 17438
count = 431
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.558655
last_received_ts = 485.558563
last_seq = 17438
current_ts = 485.575348
current_received_ts = 485.575165
current_seq = 17439
count = 432
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.575348
last_received_ts = 485.575165
last_seq = 17439
current_ts = 485.592010
current_received_ts = 485.591644
current_seq = 17440
count = 433
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.592010
last_received_ts = 485.591644
last_seq = 17440
current_ts = 485.608673
current_received_ts = 485.608490
current_seq = 17441
count = 434
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.608673
last_received_ts = 485.608490
last_seq = 17441
current_ts = 485.625336
current_received_ts = 485.625275
current_seq = 17442
count = 435
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.625336
last_received_ts = 485.625275
last_seq = 17442
current_ts = 485.641998
current_received_ts = 485.641602
current_seq = 17443
count = 436
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.641998
last_received_ts = 485.641602
last_seq = 17443
current_ts = 485.658691
current_received_ts = 485.658813
current_seq = 17444
count = 437
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.658691
last_received_ts = 485.658813
last_seq = 17444
current_ts = 485.675323
current_received_ts = 485.675262
current_seq = 17445
count = 438
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 26us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.675323
last_received_ts = 485.675262
last_seq = 17445
current_ts = 485.692017
current_received_ts = 485.691589
current_seq = 17446
count = 439
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.692017
last_received_ts = 485.691589
last_seq = 17446
current_ts = 485.708679
current_received_ts = 485.708893
current_seq = 17447
count = 440
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.708679
last_received_ts = 485.708893
last_seq = 17447
current_ts = 485.725342
current_received_ts = 485.725189
current_seq = 17448
count = 441
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.725342
last_received_ts = 485.725189
last_seq = 17448
current_ts = 485.742004
current_received_ts = 485.741638
current_seq = 17449
count = 442
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.742004
last_received_ts = 485.741638
last_seq = 17449
current_ts = 485.758667
current_received_ts = 485.758484
current_seq = 17450
count = 443
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.758667
last_received_ts = 485.758484
last_seq = 17450
current_ts = 485.775360
current_received_ts = 485.775177
current_seq = 17451
count = 444
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 18us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.775360
last_received_ts = 485.775177
last_seq = 17451
current_ts = 485.792023
current_received_ts = 485.791565
current_seq = 17452
count = 445
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.792023
last_received_ts = 485.791565
last_seq = 17452
current_ts = 485.808655
current_received_ts = 485.808533
current_seq = 17453
count = 446
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.808655
last_received_ts = 485.808533
last_seq = 17453
current_ts = 485.825348
current_received_ts = 485.825226
current_seq = 17454
count = 447
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.825348
last_received_ts = 485.825226
last_seq = 17454
current_ts = 485.842010
current_received_ts = 485.841583
current_seq = 17455
count = 448
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 22us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.842010
last_received_ts = 485.841583
last_seq = 17455
current_ts = 485.858673
current_received_ts = 485.858521
current_seq = 17456
count = 449
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.858673
last_received_ts = 485.858521
last_seq = 17456
current_ts = 485.875336
current_received_ts = 485.875305
current_seq = 17457
count = 450
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.875336
last_received_ts = 485.875305
last_seq = 17457
current_ts = 485.891998
current_received_ts = 485.891602
current_seq = 17458
count = 451
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 20us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.891998
last_received_ts = 485.891602
last_seq = 17458
current_ts = 485.908691
current_received_ts = 485.908478
current_seq = 17459
count = 452
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.908691
last_received_ts = 485.908478
last_seq = 17459
current_ts = 485.925354
current_received_ts = 485.925140
current_seq = 17460
count = 453
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 17us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.925354
last_received_ts = 485.925140
last_seq = 17460
current_ts = 485.942017
current_received_ts = 485.941620
current_seq = 17461
count = 454
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.942017
last_received_ts = 485.941620
last_seq = 17461
current_ts = 485.958679
current_received_ts = 485.958557
current_seq = 17462
count = 455
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.958679
last_received_ts = 485.958557
last_seq = 17462
current_ts = 485.975342
current_received_ts = 485.975281
current_seq = 17463
count = 456
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.975342
last_received_ts = 485.975281
last_seq = 17463
current_ts = 485.992004
current_received_ts = 485.991638
current_seq = 17464
count = 457
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 485.992004
last_received_ts = 485.991638
last_seq = 17464
current_ts = 486.008667
current_received_ts = 486.008545
current_seq = 17465
count = 458
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.008667
last_received_ts = 486.008545
last_seq = 17465
current_ts = 486.025330
current_received_ts = 486.025208
current_seq = 17466
count = 459
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.025330
last_received_ts = 486.025208
last_seq = 17466
current_ts = 486.041992
current_received_ts = 486.041626
current_seq = 17467
count = 460
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 21us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.041992
last_received_ts = 486.041626
last_seq = 17467
current_ts = 486.058685
current_received_ts = 486.058563
current_seq = 17468
count = 461
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.058685
last_received_ts = 486.058563
last_seq = 17468
current_ts = 486.075348
current_received_ts = 486.075226
current_seq = 17469
count = 462
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 23us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.075348
last_received_ts = 486.075226
last_seq = 17469
current_ts = 486.092010
current_received_ts = 486.091644
current_seq = 17470
count = 463
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.092010
last_received_ts = 486.091644
last_seq = 17470
current_ts = 486.108673
current_received_ts = 486.108551
current_seq = 17471
count = 464
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 25us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.108673
last_received_ts = 486.108551
last_seq = 17471
current_ts = 486.125336
current_received_ts = 486.125275
current_seq = 17472
count = 465
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 24us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.125336
last_received_ts = 486.125275
last_seq = 17472
current_ts = 486.141998
current_received_ts = 486.141449
current_seq = 17473
count = 466
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 13us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.141998
last_received_ts = 486.141449
last_seq = 17473
current_ts = 486.158661
current_received_ts = 486.158142
current_seq = 17474
count = 467
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 13us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.158661
last_received_ts = 486.158142
last_seq = 17474
current_ts = 486.175354
current_received_ts = 486.174896
current_seq = 17475
count = 468
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 12us
(kms_flip:2617) DEBUG: name = flip
last_ts = 486.175354
last_received_ts = 486.174896
last_seq = 17475
current_ts = 486.192017
current_received_ts = 486.191406
current_seq = 17476
count = 469
seq_step = 1
(kms_flip:2617) DEBUG: Vblank took 806us
(kms_flip:2617) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2617) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2617) igt_core-INFO: Stack trace:
(kms_flip:2617) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2617) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2617) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:2617) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
(kms_flip:2617) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2617) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2617) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2617) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (8.987s)
Dmesg
<6> [477.649765] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [477.651981] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:180]
<7> [477.652409] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:187]
<7> [477.709473] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [477.710050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [477.711074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [477.713901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [477.714400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [477.714778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [477.715378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [477.715772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [477.717668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [477.718467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [477.719049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [477.719432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [477.719813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [477.721291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [477.721700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [477.722413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [477.722800] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [477.723382] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [477.723769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [477.725951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [477.726450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [477.726831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [477.727844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [477.728432] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [477.728818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [477.730731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [477.731915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [477.732313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [477.732692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [477.733745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [477.734527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [477.735111] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [477.735511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [477.737403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [477.738213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [477.738608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [477.739734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [477.740346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [477.740733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [477.741293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [477.741686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [477.743607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [477.744420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [477.744818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.745945] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [477.745950] i915 0000:00:02.0: colorspace: RGB
<7> [477.745954] i915 0000:00:02.0: scan mode: Underscan
<7> [477.745958] i915 0000:00:02.0: colorimetry: No Data
<7> [477.745961] i915 0000:00:02.0: picture aspect: No Data
<7> [477.745965] i915 0000:00:02.0: active aspect: Same as Picture
<7> [477.745968] i915 0000:00:02.0: itc: No Data
<7> [477.745971] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [477.745975] i915 0000:00:02.0: quantization range: Full
<7> [477.745978] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [477.745981] i915 0000:00:02.0: video code: 0
<7> [477.745984] i915 0000:00:02.0: ycc quantization range: Full
<7> [477.745988] i915 0000:00:02.0: hdmi content type: Graphics
<7> [477.745991] i915 0000:00:02.0: pixel repeat: 0
<7> [477.745994] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [477.745998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.746398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [477.746775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.747478] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [477.747483] i915 0000:00:02.0: vendor: Intel
<7> [477.747486] i915 0000:00:02.0: product: Integrated gfx
<7> [477.747489] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [477.747493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.748066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [477.748468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.748845] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [477.749034] i915 0000:00:02.0: empty frame
<7> [477.749039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.749422] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [477.749807] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [477.751815] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [477.753789] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [477.754620] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [477.755212] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [477.755643] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [477.756243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [477.756642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [477.759346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [477.759841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [477.760629] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [477.761217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [477.761752] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:78:pipe A]
<7> [477.790923] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [477.793152] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [477.808925] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [477.915338] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [477.916553] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [477.920093] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [477.920698] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [477.921633] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [477.922293] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [477.936960] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [477.938063] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [477.941567] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [477.941898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [477.942402] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [477.942765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [477.943466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [477.943887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [477.944269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [477.944645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [477.945091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [477.945472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [477.945847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [477.946285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [477.946696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [477.947245] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [477.947630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [477.948039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [477.948420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [477.948799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [477.949226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [477.949617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [477.950024] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [477.950410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [477.950787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [477.951202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [477.951581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [477.951995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [477.952375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [477.952834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [477.953249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [477.953627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [477.954154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [477.954561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [477.954997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [477.955396] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [477.955774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [477.956204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [477.956614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [477.957019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [477.957399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [477.957777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [477.958217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [477.958608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [477.959033] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [477.959412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.959790] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [477.959794] i915 0000:00:02.0: colorspace: RGB
<7> [477.959798] i915 0000:00:02.0: scan mode: Underscan
<7> [477.959801] i915 0000:00:02.0: colorimetry: No Data
<7> [477.959805] i915 0000:00:02.0: picture aspect: No Data
<7> [477.959808] i915 0000:00:02.0: active aspect: Same as Picture
<7> [477.959812] i915 0000:00:02.0: itc: No Data
<7> [477.959815] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [477.959818] i915 0000:00:02.0: quantization range: Full
<7> [477.959821] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [477.959825] i915 0000:00:02.0: video code: 0
<7> [477.959828] i915 0000:00:02.0: ycc quantization range: Full
<7> [477.959831] i915 0000:00:02.0: hdmi content type: Graphics
<7> [477.959835] i915 0000:00:02.0: pixel repeat: 0
<7> [477.959838] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [477.959841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.960243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [477.960621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.961034] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [477.961038] i915 0000:00:02.0: vendor: Intel
<7> [477.961042] i915 0000:00:02.0: product: Integrated gfx
<7> [477.961045] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [477.961049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.961428] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [477.961803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [477.962197] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [477.962201] i915 0000:00:02.0: empty frame
<7> [477.962205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [477.962586] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [477.963007] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [477.963460] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [477.964022] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [477.964452] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [477.964984] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [477.965388] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [477.965766] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [477.966210] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [477.966593] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [477.967012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [477.967392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [477.967771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [477.968237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [477.968799] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Disable audio codec on [CRTC:121:pipe B]
<7> [477.986487] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [477.987136] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [478.003916] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [478.107398] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [478.108640] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [478.113209] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [478.113735] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 79200 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 4
<7> [478.114486] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [478.115008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [478.115435] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [478.116794] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [478.117466] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [478.118247] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [478.120780] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [478.121526] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [478.121604] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [478.121759] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:176:HDMI-A-2] Limiting target display pipe bpp to 30 (EDID bpp 36, max requested bpp 30, max platform bpp 36)
<7> [478.122495] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [478.123151] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 30, pipe bpp: 24, dithering: 0
<7> [478.123546] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [478.124117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [478.124509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [478.125508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [478.126194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [478.126594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [478.127201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [478.127587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [478.128251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [478.128719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [478.129412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [478.129806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [478.130373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [478.130758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [478.131511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [478.132123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [478.132520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [478.133105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [478.133492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [478.134174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [478.134640] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [478.135256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [478.135643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [478.136218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [478.136601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [478.137175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [478.137563] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [478.138522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [478.139169] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [478.139592] i915 0000:00:02.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [478.140152] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [478.142675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [478.143756] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [478.144393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [478.144784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [478.145447] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [478.145839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [478.146438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [478.146823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [478.147557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [478.148273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [478.148676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [478.149310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [478.149701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [478.150348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [478.150806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [478.151411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [478.151796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.152362] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.152742] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [478.152747] i915 0000:00:02.0: colorspace: RGB
<7> [478.152751] i915 0000:00:02.0: scan mode: Underscan
<7> [478.152755] i915 0000:00:02.0: colorimetry: No Data
<7> [478.152758] i915 0000:00:02.0: picture aspect: No Data
<7> [478.152761] i915 0000:00:02.0: active aspect: Same as Picture
<7> [478.152764] i915 0000:00:02.0: itc: No Data
<7> [478.152768] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [478.152771] i915 0000:00:02.0: quantization range: Full
<7> [478.152774] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [478.152778] i915 0000:00:02.0: video code: 0
<7> [478.152781] i915 0000:00:02.0: ycc quantization range: Full
<7> [478.152784] i915 0000:00:02.0: hdmi content type: Graphics
<7> [478.152787] i915 0000:00:02.0: pixel repeat: 0
<7> [478.152790] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [478.152795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [478.153599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.154444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.154834] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [478.154838] i915 0000:00:02.0: vendor: Intel
<7> [478.154842] i915 0000:00:02.0: product: Integrated gfx
<7> [478.154845] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [478.155137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [478.155529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.156163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.156548] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [478.156553] i915 0000:00:02.0: empty frame
<7> [478.156559] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [478.157302] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [478.157831] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [478.158462] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [478.158846] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [478.159402] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [478.159781] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [478.160371] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [478.160748] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [478.161773] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [478.162511] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [478.163141] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [478.163523] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [478.164213] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [478.164666] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [478.165260] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [478.165653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [478.166254] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [478.166641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [478.167241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [478.167636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [478.168324] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [478.168719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [478.169547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [478.170248] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [478.170644] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [478.170648] i915 0000:00:02.0: colorspace: RGB
<7> [478.170652] i915 0000:00:02.0: scan mode: Underscan
<7> [478.170656] i915 0000:00:02.0: colorimetry: No Data
<7> [478.170659] i915 0000:00:02.0: picture aspect: No Data
<7> [478.170663] i915 0000:00:02.0: active aspect: Same as Picture
<7> [478.170666] i915 0000:00:02.0: itc: No Data
<7> [478.170669] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [478.170673] i915 0000:00:02.0: quantization range: Full
<7> [478.170676] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [478.170679] i915 0000:00:02.0: video code: 0
<7> [478.170682] i915 0000:00:02.0: ycc quantization range: Full
<7> [478.170686] i915 0000:00:02.0: hdmi content type: Graphics
<7> [478.170689] i915 0000:00:02.0: pixel repeat: 0
<7> [478.170692] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [478.170696] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [478.170699] i915 0000:00:02.0: vendor: Intel
<7> [478.170703] i915 0000:00:02.0: product: Integrated gfx
<7> [478.170706] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [478.170710] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [478.170714] i915 0000:00:02.0: empty frame
<7> [478.170717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [478.171445] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [478.171829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [478.172514] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [478.173171] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [478.173556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [478.174184] i915 0000:00:02.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> [478.174567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [478.175128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [478.175514] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [478.176437] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [478.176829] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [478.177511] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [478.178076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [478.178462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [478.178841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [478.179615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [478.180285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [478.180668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [478.181223] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6300, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [478.181607] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [478.182181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [478.182575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [478.183284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.183683] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.184504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.185077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [478.185462] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:180] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [478.185842] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [478.186499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [478.187023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [478.187415] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [478.187796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [478.188510] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [478.189680] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [478.190472] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [478.191601] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [478.192761] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [478.193587] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [478.194611] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 316800 kHz, VCO 633600 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 13
<7> [478.195247] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [478.195634] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [478.196552] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [478.197272] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [478.197985] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [478.198485] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [478.216984] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD
<7> [478.230430] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [478.233638] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [478.234185] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [478.234761] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [478.235631] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [478.238221] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [478.238282] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [478.238446] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:166:HDMI-A-1] Limiting target display pipe bpp to 30 (EDID bpp 36, max requested bpp 30, max platform bpp 36)
<7> [478.239408] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [478.239819] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 30, pipe bpp: 24, dithering: 0
<7> [478.240499] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [478.241247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [478.241653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [478.242371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [478.242838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [478.243492] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [478.243509] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [478.243566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [478.244130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [478.244512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [478.245261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [478.245672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [478.246414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [478.246810] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [478.247437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [478.247823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [478.248486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [478.249172] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [478.249565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [478.250297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [478.250685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [478.251313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [478.251709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [478.252761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [478.253431] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [478.253817] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [478.254423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [478.254808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [478.255440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [478.256085] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [478.256472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [478.257130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [478.257518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [478.258074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [478.258457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [478.258835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [478.259809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [478.260474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [478.261066] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [478.261452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [478.261833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [478.262586] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [478.263230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [478.263615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [478.264166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [478.264547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.265118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.265497] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [478.265501] i915 0000:00:02.0: colorspace: RGB
<7> [478.265505] i915 0000:00:02.0: scan mode: Underscan
<7> [478.265508] i915 0000:00:02.0: colorimetry: No Data
<7> [478.265512] i915 0000:00:02.0: picture aspect: No Data
<7> [478.265515] i915 0000:00:02.0: active aspect: Same as Picture
<7> [478.265518] i915 0000:00:02.0: itc: No Data
<7> [478.265521] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [478.265524] i915 0000:00:02.0: quantization range: Full
<7> [478.265527] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [478.265531] i915 0000:00:02.0: video code: 0
<7> [478.265534] i915 0000:00:02.0: ycc quantization range: Full
<7> [478.265538] i915 0000:00:02.0: hdmi content type: Graphics
<7> [478.265541] i915 0000:00:02.0: pixel repeat: 0
<7> [478.265544] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [478.265548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [478.266346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.266743] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.267384] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [478.267389] i915 0000:00:02.0: vendor: Intel
<7> [478.267393] i915 0000:00:02.0: product: Integrated gfx
<7> [478.267397] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [478.267401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [478.267787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [478.268371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [478.268753] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [478.268757] i915 0000:00:02.0: empty frame
<7> [478.268763] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [478.269570] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [478.270282] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [478.270744] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [478.271294] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [478.271670] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36: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> [478.272215] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [478.272591] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [478.273152] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [478.273530] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [478.274500] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [478.275150] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [478.275580] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [478.276241] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [478.276634] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [478.277288] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [478.277741] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [478.278321] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [478.278703] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [478.279266] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [478.279645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [478.280264] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [478.280648] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [478.281602] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [478.282299] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [478.282682] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [478.282687] i915 0000:00:02.0: colorspace: RGB
<7> [478.282692] i915 0000:00:02.0: scan mode: Underscan
<7> [478.282695] i915 0000:00:02.0: colorimetry: No Data
<7> [478.282699] i915 0000:00:02.0: picture aspect: No Data
<7> [478.282702] i915 0000:00:02.0: active aspect: Same as Picture
<7> [478.282706] i915 0000:00:02.0: itc: No Data
<7> [478.282709] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [478.282713] i915 0000:00:02.0: quantization range: Full
<7> [478.282716] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [478.282719] i915 0000:00:02.0: video code: 0
<7> [478.282722] i915 0000:00:02.0: ycc quantization range: Full
<7> [478.282725] i915 0000:00:02.0: hdmi content type: Graphics
<7> [478.282728] i915 0000:00:02.0: pixel repeat: 0
<7> [478.282731] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [478.282735] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [478.282739] i915 0000:00:02.0: vendor: Intel
<7> [478.282742] i915 0000:00:02.0: product: Integrated gfx
<7> [478.282746] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [478.282750] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [478.282753] i915 0000:00:02.0: empty frame
<7> [478.282757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 07 00 66 10 00 01 00 00 00 00 00 00 00 00
<7> [478.283452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 05 e3 cd 0c 32 38 45 38 35 30 09 07 07 00 00 00
<7> [478.283836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [478.284476] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [478.285082] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: no, vmin: 0, vmax: 0, flipline: 0, pipeline full: 0, guardband: 0 vsync start: 0, vsync end: 0
<7> [478.285464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 0, vmax vblank: 0, vmin vtotal: 0, vmax vtotal: 0
<7> [478.285837] i915 0000:00:02.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> [478.286393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [478.286777] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9
<7> [478.287435] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [478.287820] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x40 0x9
<7> [478.288373] i915 0000:00:02.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=1083-1088 vt=1125, flags=0x9
<7> [478.288758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [478.289726] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [478.290396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [478.290781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [478.291374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [478.291758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [478.292394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [478.292822] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6300, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [478.293581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [478.294136] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [478.294516] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [478.295104] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.295486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.296040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [478.296421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [478.296799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:180] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [478.297755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [478.298411] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [478.298798] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [478.299390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [478.299773] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [478.300515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [478.301524] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [478.302388] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [478.302776] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [478.317031] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [478.317523] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [478.318930] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [478.319481] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [478.321922] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [478.322415] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [478.338627] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:78:pipe A], 32 bytes ELD
<7> [478.355269] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [478.355755] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [478.356358] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [478.356940] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<6> [486.639424] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-04-14 20:28:10