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

Result: Fail

integration-manifest git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 9.38 seconds
Hostname 
shard-glk1
Igt-Version 
IGT-Version: 2.3-gf38f4d8e9 (x86_64) (Linux: 6.19.0-rc1-CI_DRM_17718-g3b5cb684dfb4+ 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:923 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1974 run_pair()
  #3 ../tests/kms_flip.c:2165 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (9.379s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:2437) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2437) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2437) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 414.937286
last_received_ts = 414.936829
last_seq = 17565
current_ts = 414.953979
current_received_ts = 414.953461
current_seq = 17566
count = 397
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 414.953979
last_received_ts = 414.953461
last_seq = 17566
current_ts = 414.970642
current_received_ts = 414.970184
current_seq = 17567
count = 398
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 414.970642
last_received_ts = 414.970184
last_seq = 17567
current_ts = 414.987305
current_received_ts = 414.986755
current_seq = 17568
count = 399
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 414.987305
last_received_ts = 414.986755
last_seq = 17568
current_ts = 415.003998
current_received_ts = 415.003571
current_seq = 17569
count = 400
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.003998
last_received_ts = 415.003571
last_seq = 17569
current_ts = 415.020660
current_received_ts = 415.020264
current_seq = 17570
count = 401
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.020660
last_received_ts = 415.020264
last_seq = 17570
current_ts = 415.037354
current_received_ts = 415.036804
current_seq = 17571
count = 402
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.037354
last_received_ts = 415.036804
last_seq = 17571
current_ts = 415.054016
current_received_ts = 415.053589
current_seq = 17572
count = 403
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.054016
last_received_ts = 415.053589
last_seq = 17572
current_ts = 415.070679
current_received_ts = 415.070435
current_seq = 17573
count = 404
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.070679
last_received_ts = 415.070435
last_seq = 17573
current_ts = 415.087372
current_received_ts = 415.087128
current_seq = 17574
count = 405
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.087372
last_received_ts = 415.087128
last_seq = 17574
current_ts = 415.104034
current_received_ts = 415.103516
current_seq = 17575
count = 406
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.104034
last_received_ts = 415.103516
last_seq = 17575
current_ts = 415.120697
current_received_ts = 415.120789
current_seq = 17576
count = 407
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.120697
last_received_ts = 415.120789
last_seq = 17576
current_ts = 415.137390
current_received_ts = 415.136902
current_seq = 17577
count = 408
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.137390
last_received_ts = 415.136902
last_seq = 17577
current_ts = 415.154053
current_received_ts = 415.153625
current_seq = 17578
count = 409
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.154053
last_received_ts = 415.153625
last_seq = 17578
current_ts = 415.170715
current_received_ts = 415.170410
current_seq = 17579
count = 410
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.170715
last_received_ts = 415.170410
last_seq = 17579
current_ts = 415.187378
current_received_ts = 415.187531
current_seq = 17580
count = 411
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.187378
last_received_ts = 415.187531
last_seq = 17580
current_ts = 415.204071
current_received_ts = 415.203583
current_seq = 17581
count = 412
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.204071
last_received_ts = 415.203583
last_seq = 17581
current_ts = 415.220734
current_received_ts = 415.220428
current_seq = 17582
count = 413
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.220734
last_received_ts = 415.220428
last_seq = 17582
current_ts = 415.237427
current_received_ts = 415.237213
current_seq = 17583
count = 414
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.237427
last_received_ts = 415.237213
last_seq = 17583
current_ts = 415.254089
current_received_ts = 415.253601
current_seq = 17584
count = 415
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.254089
last_received_ts = 415.253601
last_seq = 17584
current_ts = 415.270752
current_received_ts = 415.270752
current_seq = 17585
count = 416
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.270752
last_received_ts = 415.270752
last_seq = 17585
current_ts = 415.287445
current_received_ts = 415.287231
current_seq = 17586
count = 417
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.287445
last_received_ts = 415.287231
last_seq = 17586
current_ts = 415.304108
current_received_ts = 415.303619
current_seq = 17587
count = 418
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.304108
last_received_ts = 415.303619
last_seq = 17587
current_ts = 415.320770
current_received_ts = 415.320465
current_seq = 17588
count = 419
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.320770
last_received_ts = 415.320465
last_seq = 17588
current_ts = 415.337463
current_received_ts = 415.337006
current_seq = 17589
count = 420
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.337463
last_received_ts = 415.337006
last_seq = 17589
current_ts = 415.354126
current_received_ts = 415.353577
current_seq = 17590
count = 421
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.354126
last_received_ts = 415.353577
last_seq = 17590
current_ts = 415.370819
current_received_ts = 415.370300
current_seq = 17591
count = 422
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.370819
last_received_ts = 415.370300
last_seq = 17591
current_ts = 415.387482
current_received_ts = 415.387573
current_seq = 17592
count = 423
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.387482
last_received_ts = 415.387573
last_seq = 17592
current_ts = 415.404144
current_received_ts = 415.403656
current_seq = 17593
count = 424
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.404144
last_received_ts = 415.403656
last_seq = 17593
current_ts = 415.420837
current_received_ts = 415.420441
current_seq = 17594
count = 425
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.420837
last_received_ts = 415.420441
last_seq = 17594
current_ts = 415.437500
current_received_ts = 415.437256
current_seq = 17595
count = 426
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.437500
last_received_ts = 415.437256
last_seq = 17595
current_ts = 415.454163
current_received_ts = 415.453918
current_seq = 17596
count = 427
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 24us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.454163
last_received_ts = 415.453918
last_seq = 17596
current_ts = 415.470825
current_received_ts = 415.470428
current_seq = 17597
count = 428
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.470825
last_received_ts = 415.470428
last_seq = 17597
current_ts = 415.487518
current_received_ts = 415.487183
current_seq = 17598
count = 429
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.487518
last_received_ts = 415.487183
last_seq = 17598
current_ts = 415.504181
current_received_ts = 415.503723
current_seq = 17599
count = 430
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.504181
last_received_ts = 415.503723
last_seq = 17599
current_ts = 415.520874
current_received_ts = 415.520325
current_seq = 17600
count = 431
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.520874
last_received_ts = 415.520325
last_seq = 17600
current_ts = 415.537537
current_received_ts = 415.537720
current_seq = 17601
count = 432
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.537537
last_received_ts = 415.537720
last_seq = 17601
current_ts = 415.554199
current_received_ts = 415.554077
current_seq = 17602
count = 433
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.554199
last_received_ts = 415.554077
last_seq = 17602
current_ts = 415.570892
current_received_ts = 415.570435
current_seq = 17603
count = 434
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.570892
last_received_ts = 415.570435
last_seq = 17603
current_ts = 415.587555
current_received_ts = 415.587402
current_seq = 17604
count = 435
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.587555
last_received_ts = 415.587402
last_seq = 17604
current_ts = 415.604218
current_received_ts = 415.603729
current_seq = 17605
count = 436
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.604218
last_received_ts = 415.603729
last_seq = 17605
current_ts = 415.620911
current_received_ts = 415.620422
current_seq = 17606
count = 437
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.620911
last_received_ts = 415.620422
last_seq = 17606
current_ts = 415.637573
current_received_ts = 415.637390
current_seq = 17607
count = 438
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.637573
last_received_ts = 415.637390
last_seq = 17607
current_ts = 415.654236
current_received_ts = 415.654053
current_seq = 17608
count = 439
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.654236
last_received_ts = 415.654053
last_seq = 17608
current_ts = 415.670929
current_received_ts = 415.670746
current_seq = 17609
count = 440
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.670929
last_received_ts = 415.670746
last_seq = 17609
current_ts = 415.687592
current_received_ts = 415.687744
current_seq = 17610
count = 441
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.687592
last_received_ts = 415.687744
last_seq = 17610
current_ts = 415.704254
current_received_ts = 415.703735
current_seq = 17611
count = 442
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.704254
last_received_ts = 415.703735
last_seq = 17611
current_ts = 415.720947
current_received_ts = 415.720856
current_seq = 17612
count = 443
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.720947
last_received_ts = 415.720856
last_seq = 17612
current_ts = 415.737610
current_received_ts = 415.737640
current_seq = 17613
count = 444
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.737610
last_received_ts = 415.737640
last_seq = 17613
current_ts = 415.754303
current_received_ts = 415.754150
current_seq = 17614
count = 445
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.754303
last_received_ts = 415.754150
last_seq = 17614
current_ts = 415.770966
current_received_ts = 415.770477
current_seq = 17615
count = 446
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.770966
last_received_ts = 415.770477
last_seq = 17615
current_ts = 415.787628
current_received_ts = 415.787628
current_seq = 17616
count = 447
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.787628
last_received_ts = 415.787628
last_seq = 17616
current_ts = 415.804321
current_received_ts = 415.804077
current_seq = 17617
count = 448
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.804321
last_received_ts = 415.804077
last_seq = 17617
current_ts = 415.820984
current_received_ts = 415.820465
current_seq = 17618
count = 449
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.820984
last_received_ts = 415.820465
last_seq = 17618
current_ts = 415.837646
current_received_ts = 415.837433
current_seq = 17619
count = 450
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.837646
last_received_ts = 415.837433
last_seq = 17619
current_ts = 415.854309
current_received_ts = 415.854095
current_seq = 17620
count = 451
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.854309
last_received_ts = 415.854095
last_seq = 17620
current_ts = 415.871002
current_received_ts = 415.870483
current_seq = 17621
count = 452
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.871002
last_received_ts = 415.870483
last_seq = 17621
current_ts = 415.887665
current_received_ts = 415.887482
current_seq = 17622
count = 453
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.887665
last_received_ts = 415.887482
last_seq = 17622
current_ts = 415.904358
current_received_ts = 415.903870
current_seq = 17623
count = 454
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.904358
last_received_ts = 415.903870
last_seq = 17623
current_ts = 415.921021
current_received_ts = 415.920502
current_seq = 17624
count = 455
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.921021
last_received_ts = 415.920502
last_seq = 17624
current_ts = 415.937683
current_received_ts = 415.937775
current_seq = 17625
count = 456
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.937683
last_received_ts = 415.937775
last_seq = 17625
current_ts = 415.954376
current_received_ts = 415.954163
current_seq = 17626
count = 457
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.954376
last_received_ts = 415.954163
last_seq = 17626
current_ts = 415.971039
current_received_ts = 415.970886
current_seq = 17627
count = 458
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.971039
last_received_ts = 415.970886
last_seq = 17627
current_ts = 415.987701
current_received_ts = 415.987549
current_seq = 17628
count = 459
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 415.987701
last_received_ts = 415.987549
last_seq = 17628
current_ts = 416.004395
current_received_ts = 416.004181
current_seq = 17629
count = 460
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.004395
last_received_ts = 416.004181
last_seq = 17629
current_ts = 416.021057
current_received_ts = 416.020569
current_seq = 17630
count = 461
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.021057
last_received_ts = 416.020569
last_seq = 17630
current_ts = 416.037720
current_received_ts = 416.037262
current_seq = 17631
count = 462
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.037720
last_received_ts = 416.037262
last_seq = 17631
current_ts = 416.054413
current_received_ts = 416.054565
current_seq = 17632
count = 463
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.054413
last_received_ts = 416.054565
last_seq = 17632
current_ts = 416.071075
current_received_ts = 416.070587
current_seq = 17633
count = 464
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.071075
last_received_ts = 416.070587
last_seq = 17633
current_ts = 416.087738
current_received_ts = 416.087524
current_seq = 17634
count = 465
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.087738
last_received_ts = 416.087524
last_seq = 17634
current_ts = 416.104431
current_received_ts = 416.104218
current_seq = 17635
count = 466
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.104431
last_received_ts = 416.104218
last_seq = 17635
current_ts = 416.121094
current_received_ts = 416.120605
current_seq = 17636
count = 467
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 17us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.121094
last_received_ts = 416.120605
last_seq = 17636
current_ts = 416.137756
current_received_ts = 416.137268
current_seq = 17637
count = 468
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.137756
last_received_ts = 416.137268
last_seq = 17637
current_ts = 416.154449
current_received_ts = 416.154541
current_seq = 17638
count = 469
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.154449
last_received_ts = 416.154541
last_seq = 17638
current_ts = 416.171112
current_received_ts = 416.170685
current_seq = 17639
count = 470
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.171112
last_received_ts = 416.170685
last_seq = 17639
current_ts = 416.187805
current_received_ts = 416.187927
current_seq = 17640
count = 471
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.187805
last_received_ts = 416.187927
last_seq = 17640
current_ts = 416.204468
current_received_ts = 416.204224
current_seq = 17641
count = 472
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.204468
last_received_ts = 416.204224
last_seq = 17641
current_ts = 416.221130
current_received_ts = 416.220673
current_seq = 17642
count = 473
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.221130
last_received_ts = 416.220673
last_seq = 17642
current_ts = 416.237823
current_received_ts = 416.237732
current_seq = 17643
count = 474
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.237823
last_received_ts = 416.237732
last_seq = 17643
current_ts = 416.254486
current_received_ts = 416.253998
current_seq = 17644
count = 475
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.254486
last_received_ts = 416.253998
last_seq = 17644
current_ts = 416.271149
current_received_ts = 416.270935
current_seq = 17645
count = 476
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 82us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.271149
last_received_ts = 416.270935
last_seq = 17645
current_ts = 416.287842
current_received_ts = 416.287476
current_seq = 17646
count = 477
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.287842
last_received_ts = 416.287476
last_seq = 17646
current_ts = 416.304504
current_received_ts = 416.304169
current_seq = 17647
count = 478
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.304504
last_received_ts = 416.304169
last_seq = 17647
current_ts = 416.321167
current_received_ts = 416.320618
current_seq = 17648
count = 479
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.321167
last_received_ts = 416.320618
last_seq = 17648
current_ts = 416.337860
current_received_ts = 416.337463
current_seq = 17649
count = 480
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.337860
last_received_ts = 416.337463
last_seq = 17649
current_ts = 416.354523
current_received_ts = 416.354614
current_seq = 17650
count = 481
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.354523
last_received_ts = 416.354614
last_seq = 17650
current_ts = 416.371185
current_received_ts = 416.370667
current_seq = 17651
count = 482
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.371185
last_received_ts = 416.370667
last_seq = 17651
current_ts = 416.387848
current_received_ts = 416.387421
current_seq = 17652
count = 483
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.387848
last_received_ts = 416.387421
last_seq = 17652
current_ts = 416.404541
current_received_ts = 416.404572
current_seq = 17653
count = 484
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.404541
last_received_ts = 416.404572
last_seq = 17653
current_ts = 416.421204
current_received_ts = 416.421295
current_seq = 17654
count = 485
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.421204
last_received_ts = 416.421295
last_seq = 17654
current_ts = 416.437866
current_received_ts = 416.437408
current_seq = 17655
count = 486
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.437866
last_received_ts = 416.437408
last_seq = 17655
current_ts = 416.454559
current_received_ts = 416.454315
current_seq = 17656
count = 487
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 18us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.454559
last_received_ts = 416.454315
last_seq = 17656
current_ts = 416.471222
current_received_ts = 416.471039
current_seq = 17657
count = 488
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.471222
last_received_ts = 416.471039
last_seq = 17657
current_ts = 416.487915
current_received_ts = 416.487732
current_seq = 17658
count = 489
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.487915
last_received_ts = 416.487732
last_seq = 17658
current_ts = 416.504578
current_received_ts = 416.504730
current_seq = 17659
count = 490
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.504578
last_received_ts = 416.504730
last_seq = 17659
current_ts = 416.521240
current_received_ts = 416.520752
current_seq = 17660
count = 491
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.521240
last_received_ts = 416.520752
last_seq = 17660
current_ts = 416.537933
current_received_ts = 416.537445
current_seq = 17661
count = 492
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.537933
last_received_ts = 416.537445
last_seq = 17661
current_ts = 416.554596
current_received_ts = 416.554565
current_seq = 17662
count = 493
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.554596
last_received_ts = 416.554565
last_seq = 17662
current_ts = 416.571259
current_received_ts = 416.571075
current_seq = 17663
count = 494
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.571259
last_received_ts = 416.571075
last_seq = 17663
current_ts = 416.587952
current_received_ts = 416.587494
current_seq = 17664
count = 495
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.587952
last_received_ts = 416.587494
last_seq = 17664
current_ts = 416.604614
current_received_ts = 416.604645
current_seq = 17665
count = 496
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.604614
last_received_ts = 416.604645
last_seq = 17665
current_ts = 416.621277
current_received_ts = 416.621094
current_seq = 17666
count = 497
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.621277
last_received_ts = 416.621094
last_seq = 17666
current_ts = 416.637970
current_received_ts = 416.637817
current_seq = 17667
count = 498
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.637970
last_received_ts = 416.637817
last_seq = 17667
current_ts = 416.654633
current_received_ts = 416.654419
current_seq = 17668
count = 499
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 17us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.654633
last_received_ts = 416.654419
last_seq = 17668
current_ts = 416.671326
current_received_ts = 416.671082
current_seq = 17669
count = 500
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.671326
last_received_ts = 416.671082
last_seq = 17669
current_ts = 416.687988
current_received_ts = 416.687469
current_seq = 17670
count = 501
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 23us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.687988
last_received_ts = 416.687469
last_seq = 17670
current_ts = 416.704651
current_received_ts = 416.704712
current_seq = 17671
count = 502
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.704651
last_received_ts = 416.704712
last_seq = 17671
current_ts = 416.721344
current_received_ts = 416.721191
current_seq = 17672
count = 503
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.721344
last_received_ts = 416.721191
last_seq = 17672
current_ts = 416.738007
current_received_ts = 416.737854
current_seq = 17673
count = 504
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.738007
last_received_ts = 416.737854
last_seq = 17673
current_ts = 416.754669
current_received_ts = 416.754425
current_seq = 17674
count = 505
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.754669
last_received_ts = 416.754425
last_seq = 17674
current_ts = 416.771362
current_received_ts = 416.770844
current_seq = 17675
count = 506
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.771362
last_received_ts = 416.770844
last_seq = 17675
current_ts = 416.788025
current_received_ts = 416.787933
current_seq = 17676
count = 507
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 50us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.788025
last_received_ts = 416.787933
last_seq = 17676
current_ts = 416.804688
current_received_ts = 416.804382
current_seq = 17677
count = 508
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.804688
last_received_ts = 416.804382
last_seq = 17677
current_ts = 416.821381
current_received_ts = 416.821228
current_seq = 17678
count = 509
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.821381
last_received_ts = 416.821228
last_seq = 17678
current_ts = 416.838043
current_received_ts = 416.837524
current_seq = 17679
count = 510
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.838043
last_received_ts = 416.837524
last_seq = 17679
current_ts = 416.854706
current_received_ts = 416.854828
current_seq = 17680
count = 511
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 22us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.854706
last_received_ts = 416.854828
last_seq = 17680
current_ts = 416.871399
current_received_ts = 416.870880
current_seq = 17681
count = 512
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 21us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.871399
last_received_ts = 416.870880
last_seq = 17681
current_ts = 416.888062
current_received_ts = 416.887634
current_seq = 17682
count = 513
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.888062
last_received_ts = 416.887634
last_seq = 17682
current_ts = 416.904724
current_received_ts = 416.904724
current_seq = 17683
count = 514
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 34us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.904724
last_received_ts = 416.904724
last_seq = 17683
current_ts = 416.921417
current_received_ts = 416.921600
current_seq = 17684
count = 515
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 20us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.921417
last_received_ts = 416.921600
last_seq = 17684
current_ts = 416.938080
current_received_ts = 416.937592
current_seq = 17685
count = 516
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 19us
(kms_flip:2437) DEBUG: name = flip
last_ts = 416.938080
last_received_ts = 416.937592
last_seq = 17685
current_ts = 416.954773
current_received_ts = 416.954437
current_seq = 17686
count = 517
seq_step = 1
(kms_flip:2437) DEBUG: Vblank took 1009us
(kms_flip:2437) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:928:
(kms_flip:2437) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2437) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2437) igt_core-INFO: Stack trace:
(kms_flip:2437) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2437) igt_core-INFO:   #1 ../tests/kms_flip.c:923 run_test_on_crtc_set.constprop.0()
(kms_flip:2437) igt_core-INFO:   #2 ../tests/kms_flip.c:1974 run_pair()
(kms_flip:2437) igt_core-INFO:   #3 ../tests/kms_flip.c:2165 __igt_unique____real_main2045()
(kms_flip:2437) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2437) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2437) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2437) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (9.379s)
Dmesg
<6> [407.488961] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [407.490899] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:182]
<7> [407.491439] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:183]
<7> [407.529389] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:76:pipe A]
<7> [407.529667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [407.530253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [407.530675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [407.531152] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [407.531573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [407.532016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [407.532426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [407.532835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [407.533302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [407.533711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [407.534143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [407.534550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [407.534979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [407.535386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [407.535795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [407.536217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [407.536625] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [407.537059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [407.537467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [407.537874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [407.538305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [407.538712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [407.539144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [407.539549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [407.539982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [407.540389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [407.540794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [407.541227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [407.541633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [407.542057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [407.542474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [407.542881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [407.543313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [407.543719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [407.544145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [407.544555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [407.544992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [407.545402] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [407.545808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [407.546233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [407.546645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [407.547076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [407.547483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in avi infoframe
<7> [407.547910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.548318] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [407.548322] i915 0000:00:02.0: colorspace: RGB
<7> [407.548326] i915 0000:00:02.0: scan mode: Underscan
<7> [407.548330] i915 0000:00:02.0: colorimetry: No Data
<7> [407.548333] i915 0000:00:02.0: picture aspect: No Data
<7> [407.548336] i915 0000:00:02.0: active aspect: Same as Picture
<7> [407.548339] i915 0000:00:02.0: itc: No Data
<7> [407.548342] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [407.548345] i915 0000:00:02.0: quantization range: Full
<7> [407.548348] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [407.548352] i915 0000:00:02.0: video code: 0
<7> [407.548355] i915 0000:00:02.0: ycc quantization range: Full
<7> [407.548358] i915 0000:00:02.0: hdmi content type: Graphics
<7> [407.548361] i915 0000:00:02.0: pixel repeat: 0
<7> [407.548364] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [407.548368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.548772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in spd infoframe
<7> [407.549203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.549607] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [407.549612] i915 0000:00:02.0: vendor: Intel
<7> [407.549615] i915 0000:00:02.0: product: Integrated gfx
<7> [407.549618] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [407.549622] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.550044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hdmi infoframe
<7> [407.550450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.550854] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [407.550862] i915 0000:00:02.0: empty frame
<7> [407.550865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.551295] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:76:pipe A] fastset requirement not met, forcing full modeset
<7> [407.551702] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:76:pipe A] releasing PORT PLL B
<7> [407.552564] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:76:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [407.553120] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:119:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [407.553598] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:70:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [407.554021] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:113:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [407.554519] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:76:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [407.555013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:76:pipe A] enable: no [modeset]
<7> [407.555442] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [407.555850] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [407.556296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:52:plane 3A] fb: [NOFB], visible: no
<7> [407.556701] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:61:plane 4A] fb: [NOFB], visible: no
<7> [407.557130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:70:cursor A] fb: [NOFB], visible: no
<7> [407.559926] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:164:HDMI-A-1][ENCODER:163:DDI B/PHY B] Disable audio codec on [CRTC:76:pipe A]
<7> [407.582585] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [407.584555] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [407.601029] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [407.708107] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [407.709286] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:76:pipe A]
<7> [407.713001] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [407.713561] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DDI B/PHY B]
<7> [407.714063] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:173:DDI C/PHY C]
<7> [407.714472] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:164:HDMI-A-1]
<7> [407.730394] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [407.731759] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:76:pipe A]
<7> [407.735615] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:119:pipe B]
<7> [407.735794] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [407.736373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [407.736781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [407.737213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [407.737623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [407.738058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [407.738468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [407.738908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [407.739316] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [407.739724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [407.740167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [407.740574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [407.741013] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [407.741420] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [407.741867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [407.742275] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [407.742683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [407.743123] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [407.743530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [407.743963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [407.744370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [407.744775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [407.745201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [407.745607] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [407.746039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [407.746444] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [407.746900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [407.747308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [407.747713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [407.748143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [407.748551] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [407.748973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [407.749379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [407.749782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [407.750213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [407.750620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [407.751046] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [407.751465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [407.751890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [407.752295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [407.752701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [407.753126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [407.753535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in avi infoframe
<7> [407.753968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.754374] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [407.754379] i915 0000:00:02.0: colorspace: RGB
<7> [407.754383] i915 0000:00:02.0: scan mode: Underscan
<7> [407.754386] i915 0000:00:02.0: colorimetry: No Data
<7> [407.754389] i915 0000:00:02.0: picture aspect: No Data
<7> [407.754392] i915 0000:00:02.0: active aspect: Same as Picture
<7> [407.754395] i915 0000:00:02.0: itc: No Data
<7> [407.754398] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [407.754402] i915 0000:00:02.0: quantization range: Full
<7> [407.754404] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [407.754408] i915 0000:00:02.0: video code: 0
<7> [407.754411] i915 0000:00:02.0: ycc quantization range: Full
<7> [407.754414] i915 0000:00:02.0: hdmi content type: Graphics
<7> [407.754417] i915 0000:00:02.0: pixel repeat: 0
<7> [407.754420] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [407.754424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.754846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in spd infoframe
<7> [407.755253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.755659] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [407.755663] i915 0000:00:02.0: vendor: Intel
<7> [407.755666] i915 0000:00:02.0: product: Integrated gfx
<7> [407.755669] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [407.755673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.756103] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:119:pipe B] fastset requirement not met in hdmi infoframe
<7> [407.756510] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.756931] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [407.756936] i915 0000:00:02.0: empty frame
<7> [407.756939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.757345] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:119:pipe B] fastset requirement not met, forcing full modeset
<7> [407.757753] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:119:pipe B] releasing PORT PLL C
<7> [407.758846] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:119:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [407.759423] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:113:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [407.759996] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:119:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [407.760463] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [407.760897] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [407.761302] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [407.761715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:119:pipe B] enable: no [modeset]
<7> [407.762163] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:plane 1B] fb: [NOFB], visible: no
<7> [407.762575] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:86:plane 2B] fb: [NOFB], visible: no
<7> [407.763016] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3B] fb: [NOFB], visible: no
<7> [407.763425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:104:plane 4B] fb: [NOFB], visible: no
<7> [407.763875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:113:cursor B] fb: [NOFB], visible: no
<7> [407.764495] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:174:HDMI-A-2][ENCODER:173:DDI C/PHY C] Disable audio codec on [CRTC:119:pipe B]
<7> [407.796688] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [407.797419] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [407.814300] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [407.916205] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [407.917556] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:119:pipe B]
<7> [407.918434] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [407.919046] 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> [407.919704] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DDI B/PHY B]
<7> [407.920345] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:173:DDI C/PHY C]
<7> [407.921057] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:174:HDMI-A-2]
<7> [407.921830] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [407.922268] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [407.922903] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:119:pipe B]
<7> [407.923913] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:162:pipe C]
<7> [407.924280] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:162:pipe C]
<7> [407.924334] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:174:HDMI-A-2]
<7> [407.924618] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [407.925190] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:162:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [407.925600] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:173:DDI C/PHY C] [CRTC:162:pipe C]
<7> [407.926032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [407.926441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [407.926885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [407.927291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [407.927696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [407.928151] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [407.928557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [407.929003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [407.929411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [407.929860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [407.930267] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [407.930672] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [407.931117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [407.931524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [407.931972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [407.932380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [407.932829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [407.933237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [407.933643] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [407.934079] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [407.934485] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [407.934913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [407.935320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [407.935730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [407.936165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [407.936574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [407.937000] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [407.937408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [407.937841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [407.938254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [407.938659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [407.939081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [407.939487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [407.939921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [407.940328] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [407.940734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [407.941161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [407.941570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [407.942017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [407.942230] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [407.942427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [407.942669] i915 0000:00:02.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [407.942849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [407.943108] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [407.943255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [407.943668] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in avi infoframe
<7> [407.944093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.944496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.944508] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [407.944910] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [407.944916] i915 0000:00:02.0: colorspace: RGB
<7> [407.944920] i915 0000:00:02.0: scan mode: Underscan
<7> [407.944924] i915 0000:00:02.0: colorimetry: No Data
<7> [407.944927] i915 0000:00:02.0: picture aspect: No Data
<7> [407.944930] i915 0000:00:02.0: active aspect: Same as Picture
<7> [407.944934] i915 0000:00:02.0: itc: No Data
<7> [407.944937] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [407.944940] i915 0000:00:02.0: quantization range: Full
<7> [407.944943] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [407.944946] i915 0000:00:02.0: video code: 0
<7> [407.944949] i915 0000:00:02.0: ycc quantization range: Full
<7> [407.944952] i915 0000:00:02.0: hdmi content type: Graphics
<7> [407.944956] i915 0000:00:02.0: pixel repeat: 0
<7> [407.944959] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [407.944962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in spd infoframe
<7> [407.945368] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.945792] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.946199] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [407.946203] i915 0000:00:02.0: vendor: Intel
<7> [407.946206] i915 0000:00:02.0: product: Integrated gfx
<7> [407.946209] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [407.946213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:162:pipe C] fastset requirement not met in hdmi infoframe
<7> [407.946617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [407.947054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [407.947458] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [407.947467] i915 0000:00:02.0: empty frame
<7> [407.947472] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:162:pipe C] fastset requirement not met, forcing full modeset
<7> [407.948099] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:162:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [407.948613] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:120:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [407.949044] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:156:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [407.949447] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:120: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> [407.949869] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:120: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> [407.950293] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:120: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> [407.950698] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:120: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> [407.951276] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:162:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [407.951724] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [407.952213] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [407.952617] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [407.953048] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [407.953471] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:162:pipe C] using pre-allocated PORT PLL C
<7> [407.953910] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:162:pipe C] reserving PORT PLL C
<7> [407.954326] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:162:pipe C] enable: yes [modeset]
<7> [407.954733] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [407.955165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [407.955571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [407.956000] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [407.956404] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [407.956853] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [407.957258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [407.957734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [407.958167] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [407.958172] i915 0000:00:02.0: colorspace: RGB
<7> [407.958175] i915 0000:00:02.0: scan mode: Underscan
<7> [407.958179] i915 0000:00:02.0: colorimetry: No Data
<7> [407.958182] i915 0000:00:02.0: picture aspect: No Data
<7> [407.958186] i915 0000:00:02.0: active aspect: Same as Picture
<7> [407.958189] i915 0000:00:02.0: itc: No Data
<7> [407.958192] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [407.958196] i915 0000:00:02.0: quantization range: Full
<7> [407.958199] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [407.958202] i915 0000:00:02.0: video code: 0
<7> [407.958205] i915 0000:00:02.0: ycc quantization range: Full
<7> [407.958208] i915 0000:00:02.0: hdmi content type: Graphics
<7> [407.958211] i915 0000:00:02.0: pixel repeat: 0
<7> [407.958214] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [407.958218] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [407.958222] i915 0000:00:02.0: vendor: Intel
<7> [407.958280] i915 0000:00:02.0: product: Integrated gfx
<7> [407.958284] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [407.958288] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [407.958292] i915 0000:00:02.0: empty frame
<7> [407.958296] 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> [407.958722] 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> [407.959164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [407.959570] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [407.959999] 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> [407.960407] 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> [407.960835] 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> [407.961244] 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> [407.961649] 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> [407.962087] 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> [407.962493] 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> [407.962927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [407.963333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [407.963762] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [407.964167] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [407.964572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [407.964997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [407.965407] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [407.965838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [407.966244] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [407.966647] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [407.967073] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [407.967477] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [407.967906] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [407.968313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [407.968718] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 1C] fb: [FB:182] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [407.969145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [407.969550] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [407.969986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:plane 2C] fb: [NOFB], visible: no
<7> [407.970393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:138:plane 3C] fb: [NOFB], visible: no
<7> [407.970821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:147:plane 4C] fb: [NOFB], visible: no
<7> [407.971226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:156:cursor C] fb: [NOFB], visible: no
<7> [407.972496] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [407.973046] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [407.974006] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [407.975179] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [407.975714] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [407.976476] 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> [407.976953] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DDI B/PHY B]
<7> [407.977375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:173:DDI C/PHY C]
<7> [407.978005] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:162:pipe C]
<7> [407.978417] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [407.979035] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [407.979632] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [407.997835] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:174:HDMI-A-2][ENCODER:173:DDI C/PHY C] Enable audio codec on [CRTC:162:pipe C], 32 bytes ELD
<7> [408.011266] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [408.014484] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [408.015046] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [408.015660] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:174:HDMI-A-2]
<7> [408.019032] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:162:pipe C]
<7> [408.024186] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:76:pipe A]
<7> [408.024246] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:164:HDMI-A-1]
<7> [408.024457] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [408.025096] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:76:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [408.025508] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:163:DDI B/PHY B] [CRTC:76:pipe A]
<7> [408.025958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [408.026389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [408.026675] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [408.026691] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [408.026831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [408.027240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [408.027751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [408.028162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [408.028639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [408.029096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [408.029511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [408.029981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [408.030391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [408.030836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [408.031283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [408.031691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [408.032120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [408.032525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [408.032958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [408.033363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [408.033788] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [408.034194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [408.034600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [408.035029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [408.035435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [408.035861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [408.036266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [408.036670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [408.037102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [408.037509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [408.037948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [408.038355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [408.038784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [408.039190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [408.039594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [408.040017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [408.040424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [408.040857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [408.041264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [408.041669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [408.042091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [408.042497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [408.042930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [408.043336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [408.043758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in avi infoframe
<7> [408.044164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [408.044568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [408.044989] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [408.044993] i915 0000:00:02.0: colorspace: RGB
<7> [408.044997] i915 0000:00:02.0: scan mode: Underscan
<7> [408.045000] i915 0000:00:02.0: colorimetry: No Data
<7> [408.045003] i915 0000:00:02.0: picture aspect: No Data
<7> [408.045006] i915 0000:00:02.0: active aspect: Same as Picture
<7> [408.045010] i915 0000:00:02.0: itc: No Data
<7> [408.045012] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [408.045016] i915 0000:00:02.0: quantization range: Full
<7> [408.045019] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [408.045022] i915 0000:00:02.0: video code: 0
<7> [408.045025] i915 0000:00:02.0: ycc quantization range: Full
<7> [408.045028] i915 0000:00:02.0: hdmi content type: Graphics
<7> [408.045031] i915 0000:00:02.0: pixel repeat: 0
<7> [408.045034] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [408.045038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in spd infoframe
<7> [408.045443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [408.045864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [408.046269] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [408.046273] i915 0000:00:02.0: vendor: Intel
<7> [408.046276] i915 0000:00:02.0: product: Integrated gfx
<7> [408.046279] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [408.046283] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:76:pipe A] fastset requirement not met in hdmi infoframe
<7> [408.046688] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [408.047117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [408.047522] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [408.047528] i915 0000:00:02.0: empty frame
<7> [408.047534] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:76:pipe A] fastset requirement not met, forcing full modeset
<7> [408.048213] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:76:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [408.048657] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:162:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [408.049162] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [408.049578] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:70:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [408.050013] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [408.050432] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [408.050850] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [408.051248] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [408.051645] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:120:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [408.052064] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:156:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [408.052631] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:76:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [408.053099] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [408.053507] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:76:pipe A] using pre-allocated PORT PLL B
<7> [408.053941] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:76:pipe A] reserving PORT PLL B
<7> [408.054361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:76:pipe A] enable: yes [modeset]
<7> [408.054797] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [408.055204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [408.055608] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [408.056035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [408.056525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [408.056956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [408.057470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [408.057911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [408.058316] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [408.058321] i915 0000:00:02.0: colorspace: RGB
<7> [408.058324] i915 0000:00:02.0: scan mode: Underscan
<7> [408.058328] i915 0000:00:02.0: colorimetry: No Data
<7> [408.058331] i915 0000:00:02.0: picture aspect: No Data
<7> [408.058334] i915 0000:00:02.0: active aspect: Same as Picture
<7> [408.058337] i915 0000:00:02.0: itc: No Data
<7> [408.058340] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [408.058343] i915 0000:00:02.0: quantization range: Full
<7> [408.058346] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [408.058350] i915 0000:00:02.0: video code: 0
<7> [408.058353] i915 0000:00:02.0: ycc quantization range: Full
<7> [408.058356] i915 0000:00:02.0: hdmi content type: Graphics
<7> [408.058359] i915 0000:00:02.0: pixel repeat: 0
<7> [408.058362] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [408.058366] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [408.058369] i915 0000:00:02.0: vendor: Intel
<7> [408.058372] i915 0000:00:02.0: product: Integrated gfx
<7> [408.058375] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [408.058379] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [408.058383] i915 0000:00:02.0: empty frame
<7> [408.058386] 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> [408.058834] 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> [408.059239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [408.059641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [408.060073] 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> [408.060478] 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> [408.060908] 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> [408.061314] 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> [408.061745] 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> [408.062152] 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> [408.062556] 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> [408.062982] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 192000
<7> [408.063386] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [408.063815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [408.064221] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [408.064625] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [408.065050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: ebb0: 0x6200, ebb4: 0x2000,pll0: 0x21, pll1: 0x100, pll2: 0x1a6666, pll3: 0x10000, pll6: 0x30904, pll8: 0x8, pll9: 0xa, pll10: 0x8003c00, pcsdw12: 0x4d
<7> [408.065456] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [408.065886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [408.066289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [408.066692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [408.067106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [408.067508] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [408.067928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [408.068332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [408.068752] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:182] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [408.069159] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [408.069561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [408.069991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [408.070395] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:52:plane 3A] fb: [NOFB], visible: no
<7> [408.070815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:61:plane 4A] fb: [NOFB], visible: no
<7> [408.071218] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:70:cursor A] fb: [NOFB], visible: no
<7> [408.072094] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [408.072840] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:163:DDI B/PHY B]
<7> [408.073298] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:173:DDI C/PHY C]
<7> [408.081200] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:76:pipe A]
<7> [408.081750] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [408.082297] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [408.082913] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [408.084276] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [408.084910] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [408.100953] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:164:HDMI-A-1][ENCODER:163:DDI B/PHY B] Enable audio codec on [CRTC:76:pipe A], 32 bytes ELD
<7> [408.117639] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [408.118167] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [408.119940] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:164:HDMI-A-1]
<7> [408.120631] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:76:pipe A]
<6> [416.871807] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2025-12-22 23:02:08