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

Result: Fail

git-log-oneline i915_display_info1 igt_runner1 runtimes1 results1.json results1-i915-load.json guc_logs1.tar boot1 dmesg1

DetailValue
Duration 9.90 seconds
Hostname 
shard-glk3
Igt-Version 
IGT-Version: 2.4-gb810708a0 (x86_64) (Linux: 7.1.0-rc2-CI_DRM_18411-g94d56a898a2d+ x86_64)
Out 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1083 1088 1125 0x48 0x9 
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:2029 run_pair()
  #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (9.905s)
Err 
Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
(kms_flip:2382) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2382) CRITICAL: Failed assertion: end - start < 500
Dynamic subtest AC-HDMI-A1-HDMI-A2 failed.
**** DEBUG ****
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.529861
last_received_ts = 189.529449
last_seq = 6025
current_ts = 189.546539
current_received_ts = 189.546204
current_seq = 6026
count = 405
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.546539
last_received_ts = 189.546204
last_seq = 6026
current_ts = 189.563202
current_received_ts = 189.562866
current_seq = 6027
count = 406
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 17us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.563202
last_received_ts = 189.562866
last_seq = 6027
current_ts = 189.579865
current_received_ts = 189.579575
current_seq = 6028
count = 407
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.579865
last_received_ts = 189.579575
last_seq = 6028
current_ts = 189.596542
current_received_ts = 189.596497
current_seq = 6029
count = 408
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 25us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.596542
last_received_ts = 189.596497
last_seq = 6029
current_ts = 189.613205
current_received_ts = 189.613174
current_seq = 6030
count = 409
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 19us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.613205
last_received_ts = 189.613174
last_seq = 6030
current_ts = 189.629868
current_received_ts = 189.630112
current_seq = 6031
count = 410
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.629868
last_received_ts = 189.630112
last_seq = 6031
current_ts = 189.646530
current_received_ts = 189.646042
current_seq = 6032
count = 411
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.646530
last_received_ts = 189.646042
last_seq = 6032
current_ts = 189.663208
current_received_ts = 189.663132
current_seq = 6033
count = 412
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.663208
last_received_ts = 189.663132
last_seq = 6033
current_ts = 189.679871
current_received_ts = 189.679520
current_seq = 6034
count = 413
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.679871
last_received_ts = 189.679520
last_seq = 6034
current_ts = 189.696548
current_received_ts = 189.696228
current_seq = 6035
count = 414
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.696548
last_received_ts = 189.696228
last_seq = 6035
current_ts = 189.713211
current_received_ts = 189.712936
current_seq = 6036
count = 415
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.713211
last_received_ts = 189.712936
last_seq = 6036
current_ts = 189.729874
current_received_ts = 189.729599
current_seq = 6037
count = 416
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.729874
last_received_ts = 189.729599
last_seq = 6037
current_ts = 189.746536
current_received_ts = 189.746292
current_seq = 6038
count = 417
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.746536
last_received_ts = 189.746292
last_seq = 6038
current_ts = 189.763214
current_received_ts = 189.762955
current_seq = 6039
count = 418
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 65us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.763214
last_received_ts = 189.762955
last_seq = 6039
current_ts = 189.779877
current_received_ts = 189.779572
current_seq = 6040
count = 419
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 12us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.779877
last_received_ts = 189.779572
last_seq = 6040
current_ts = 189.796539
current_received_ts = 189.796265
current_seq = 6041
count = 420
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.796539
last_received_ts = 189.796265
last_seq = 6041
current_ts = 189.813202
current_received_ts = 189.812927
current_seq = 6042
count = 421
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.813202
last_received_ts = 189.812927
last_seq = 6042
current_ts = 189.829880
current_received_ts = 189.829575
current_seq = 6043
count = 422
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.829880
last_received_ts = 189.829575
last_seq = 6043
current_ts = 189.846542
current_received_ts = 189.846252
current_seq = 6044
count = 423
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.846542
last_received_ts = 189.846252
last_seq = 6044
current_ts = 189.863205
current_received_ts = 189.862915
current_seq = 6045
count = 424
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.863205
last_received_ts = 189.862915
last_seq = 6045
current_ts = 189.879883
current_received_ts = 189.879578
current_seq = 6046
count = 425
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.879883
last_received_ts = 189.879578
last_seq = 6046
current_ts = 189.896545
current_received_ts = 189.896255
current_seq = 6047
count = 426
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.896545
last_received_ts = 189.896255
last_seq = 6047
current_ts = 189.913208
current_received_ts = 189.912674
current_seq = 6048
count = 427
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.913208
last_received_ts = 189.912674
last_seq = 6048
current_ts = 189.929886
current_received_ts = 189.929626
current_seq = 6049
count = 428
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.929886
last_received_ts = 189.929626
last_seq = 6049
current_ts = 189.946548
current_received_ts = 189.946259
current_seq = 6050
count = 429
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.946548
last_received_ts = 189.946259
last_seq = 6050
current_ts = 189.963211
current_received_ts = 189.962906
current_seq = 6051
count = 430
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.963211
last_received_ts = 189.962906
last_seq = 6051
current_ts = 189.979874
current_received_ts = 189.979584
current_seq = 6052
count = 431
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.979874
last_received_ts = 189.979584
last_seq = 6052
current_ts = 189.996552
current_received_ts = 189.996262
current_seq = 6053
count = 432
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 189.996552
last_received_ts = 189.996262
last_seq = 6053
current_ts = 190.013214
current_received_ts = 190.012909
current_seq = 6054
count = 433
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.013214
last_received_ts = 190.012909
last_seq = 6054
current_ts = 190.029877
current_received_ts = 190.029587
current_seq = 6055
count = 434
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.029877
last_received_ts = 190.029587
last_seq = 6055
current_ts = 190.046539
current_received_ts = 190.046265
current_seq = 6056
count = 435
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.046539
last_received_ts = 190.046265
last_seq = 6056
current_ts = 190.063217
current_received_ts = 190.062958
current_seq = 6057
count = 436
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 68us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.063217
last_received_ts = 190.062958
last_seq = 6057
current_ts = 190.079865
current_received_ts = 190.079590
current_seq = 6058
count = 437
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.079865
last_received_ts = 190.079590
last_seq = 6058
current_ts = 190.096542
current_received_ts = 190.096268
current_seq = 6059
count = 438
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.096542
last_received_ts = 190.096268
last_seq = 6059
current_ts = 190.113220
current_received_ts = 190.112930
current_seq = 6060
count = 439
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.113220
last_received_ts = 190.112930
last_seq = 6060
current_ts = 190.129883
current_received_ts = 190.129593
current_seq = 6061
count = 440
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.129883
last_received_ts = 190.129593
last_seq = 6061
current_ts = 190.146545
current_received_ts = 190.146255
current_seq = 6062
count = 441
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 12us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.146545
last_received_ts = 190.146255
last_seq = 6062
current_ts = 190.163208
current_received_ts = 190.162949
current_seq = 6063
count = 442
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.163208
last_received_ts = 190.162949
last_seq = 6063
current_ts = 190.179886
current_received_ts = 190.179596
current_seq = 6064
count = 443
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.179886
last_received_ts = 190.179596
last_seq = 6064
current_ts = 190.196548
current_received_ts = 190.196274
current_seq = 6065
count = 444
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.196548
last_received_ts = 190.196274
last_seq = 6065
current_ts = 190.213196
current_received_ts = 190.212952
current_seq = 6066
count = 445
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.213196
last_received_ts = 190.212952
last_seq = 6066
current_ts = 190.229874
current_received_ts = 190.229584
current_seq = 6067
count = 446
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.229874
last_received_ts = 190.229584
last_seq = 6067
current_ts = 190.246552
current_received_ts = 190.246262
current_seq = 6068
count = 447
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.246552
last_received_ts = 190.246262
last_seq = 6068
current_ts = 190.263214
current_received_ts = 190.262955
current_seq = 6069
count = 448
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 81us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.263214
last_received_ts = 190.262955
last_seq = 6069
current_ts = 190.279877
current_received_ts = 190.279602
current_seq = 6070
count = 449
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.279877
last_received_ts = 190.279602
last_seq = 6070
current_ts = 190.296555
current_received_ts = 190.296265
current_seq = 6071
count = 450
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.296555
last_received_ts = 190.296265
last_seq = 6071
current_ts = 190.313217
current_received_ts = 190.312943
current_seq = 6072
count = 451
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.313217
last_received_ts = 190.312943
last_seq = 6072
current_ts = 190.329880
current_received_ts = 190.329590
current_seq = 6073
count = 452
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.329880
last_received_ts = 190.329590
last_seq = 6073
current_ts = 190.346542
current_received_ts = 190.346283
current_seq = 6074
count = 453
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.346542
last_received_ts = 190.346283
last_seq = 6074
current_ts = 190.363220
current_received_ts = 190.362961
current_seq = 6075
count = 454
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.363220
last_received_ts = 190.362961
last_seq = 6075
current_ts = 190.379868
current_received_ts = 190.379547
current_seq = 6076
count = 455
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.379868
last_received_ts = 190.379547
last_seq = 6076
current_ts = 190.396545
current_received_ts = 190.396057
current_seq = 6077
count = 456
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.396545
last_received_ts = 190.396057
last_seq = 6077
current_ts = 190.413208
current_received_ts = 190.412659
current_seq = 6078
count = 457
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.413208
last_received_ts = 190.412659
last_seq = 6078
current_ts = 190.429871
current_received_ts = 190.429626
current_seq = 6079
count = 458
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.429871
last_received_ts = 190.429626
last_seq = 6079
current_ts = 190.446548
current_received_ts = 190.446045
current_seq = 6080
count = 459
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.446548
last_received_ts = 190.446045
last_seq = 6080
current_ts = 190.463211
current_received_ts = 190.462982
current_seq = 6081
count = 460
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.463211
last_received_ts = 190.462982
last_seq = 6081
current_ts = 190.479889
current_received_ts = 190.479630
current_seq = 6082
count = 461
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.479889
last_received_ts = 190.479630
last_seq = 6082
current_ts = 190.496552
current_received_ts = 190.496277
current_seq = 6083
count = 462
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.496552
last_received_ts = 190.496277
last_seq = 6083
current_ts = 190.513199
current_received_ts = 190.512970
current_seq = 6084
count = 463
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 67us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.513199
last_received_ts = 190.512970
last_seq = 6084
current_ts = 190.529877
current_received_ts = 190.529617
current_seq = 6085
count = 464
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.529877
last_received_ts = 190.529617
last_seq = 6085
current_ts = 190.546555
current_received_ts = 190.546295
current_seq = 6086
count = 465
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.546555
last_received_ts = 190.546295
last_seq = 6086
current_ts = 190.563202
current_received_ts = 190.562973
current_seq = 6087
count = 466
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.563202
last_received_ts = 190.562973
last_seq = 6087
current_ts = 190.579880
current_received_ts = 190.579590
current_seq = 6088
count = 467
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 12us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.579880
last_received_ts = 190.579590
last_seq = 6088
current_ts = 190.596558
current_received_ts = 190.596283
current_seq = 6089
count = 468
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.596558
last_received_ts = 190.596283
last_seq = 6089
current_ts = 190.613220
current_received_ts = 190.612930
current_seq = 6090
count = 469
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.613220
last_received_ts = 190.612930
last_seq = 6090
current_ts = 190.629883
current_received_ts = 190.629593
current_seq = 6091
count = 470
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.629883
last_received_ts = 190.629593
last_seq = 6091
current_ts = 190.646545
current_received_ts = 190.646271
current_seq = 6092
count = 471
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.646545
last_received_ts = 190.646271
last_seq = 6092
current_ts = 190.663208
current_received_ts = 190.662949
current_seq = 6093
count = 472
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.663208
last_received_ts = 190.662949
last_seq = 6093
current_ts = 190.679886
current_received_ts = 190.679581
current_seq = 6094
count = 473
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.679886
last_received_ts = 190.679581
last_seq = 6094
current_ts = 190.696548
current_received_ts = 190.696152
current_seq = 6095
count = 474
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.696548
last_received_ts = 190.696152
last_seq = 6095
current_ts = 190.713211
current_received_ts = 190.712769
current_seq = 6096
count = 475
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.713211
last_received_ts = 190.712769
last_seq = 6096
current_ts = 190.729874
current_received_ts = 190.729416
current_seq = 6097
count = 476
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.729874
last_received_ts = 190.729416
last_seq = 6097
current_ts = 190.746536
current_received_ts = 190.746109
current_seq = 6098
count = 477
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.746536
last_received_ts = 190.746109
last_seq = 6098
current_ts = 190.763199
current_received_ts = 190.762756
current_seq = 6099
count = 478
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 6us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.763199
last_received_ts = 190.762756
last_seq = 6099
current_ts = 190.779877
current_received_ts = 190.779434
current_seq = 6100
count = 479
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.779877
last_received_ts = 190.779434
last_seq = 6100
current_ts = 190.796539
current_received_ts = 190.796112
current_seq = 6101
count = 480
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 8us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.796539
last_received_ts = 190.796112
last_seq = 6101
current_ts = 190.813217
current_received_ts = 190.812759
current_seq = 6102
count = 481
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.813217
last_received_ts = 190.812759
last_seq = 6102
current_ts = 190.829880
current_received_ts = 190.829422
current_seq = 6103
count = 482
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.829880
last_received_ts = 190.829422
last_seq = 6103
current_ts = 190.846542
current_received_ts = 190.846298
current_seq = 6104
count = 483
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.846542
last_received_ts = 190.846298
last_seq = 6104
current_ts = 190.863220
current_received_ts = 190.862930
current_seq = 6105
count = 484
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.863220
last_received_ts = 190.862930
last_seq = 6105
current_ts = 190.879883
current_received_ts = 190.879593
current_seq = 6106
count = 485
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.879883
last_received_ts = 190.879593
last_seq = 6106
current_ts = 190.896545
current_received_ts = 190.896286
current_seq = 6107
count = 486
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.896545
last_received_ts = 190.896286
last_seq = 6107
current_ts = 190.913223
current_received_ts = 190.912949
current_seq = 6108
count = 487
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.913223
last_received_ts = 190.912949
last_seq = 6108
current_ts = 190.929886
current_received_ts = 190.929581
current_seq = 6109
count = 488
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.929886
last_received_ts = 190.929581
last_seq = 6109
current_ts = 190.946548
current_received_ts = 190.946121
current_seq = 6110
count = 489
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.946548
last_received_ts = 190.946121
last_seq = 6110
current_ts = 190.963211
current_received_ts = 190.962753
current_seq = 6111
count = 490
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 6us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.963211
last_received_ts = 190.962753
last_seq = 6111
current_ts = 190.979889
current_received_ts = 190.979599
current_seq = 6112
count = 491
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.979889
last_received_ts = 190.979599
last_seq = 6112
current_ts = 190.996552
current_received_ts = 190.996262
current_seq = 6113
count = 492
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 190.996552
last_received_ts = 190.996262
last_seq = 6113
current_ts = 191.013214
current_received_ts = 191.012924
current_seq = 6114
count = 493
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.013214
last_received_ts = 191.012924
last_seq = 6114
current_ts = 191.029892
current_received_ts = 191.029587
current_seq = 6115
count = 494
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.029892
last_received_ts = 191.029587
last_seq = 6115
current_ts = 191.046555
current_received_ts = 191.046295
current_seq = 6116
count = 495
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.046555
last_received_ts = 191.046295
last_seq = 6116
current_ts = 191.063217
current_received_ts = 191.062927
current_seq = 6117
count = 496
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.063217
last_received_ts = 191.062927
last_seq = 6117
current_ts = 191.079880
current_received_ts = 191.079437
current_seq = 6118
count = 497
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 11us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.079880
last_received_ts = 191.079437
last_seq = 6118
current_ts = 191.096542
current_received_ts = 191.096115
current_seq = 6119
count = 498
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.096542
last_received_ts = 191.096115
last_seq = 6119
current_ts = 191.113205
current_received_ts = 191.112762
current_seq = 6120
count = 499
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.113205
last_received_ts = 191.112762
last_seq = 6120
current_ts = 191.129883
current_received_ts = 191.129425
current_seq = 6121
count = 500
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.129883
last_received_ts = 191.129425
last_seq = 6121
current_ts = 191.146545
current_received_ts = 191.146118
current_seq = 6122
count = 501
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 8us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.146545
last_received_ts = 191.146118
last_seq = 6122
current_ts = 191.163208
current_received_ts = 191.162781
current_seq = 6123
count = 502
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 8us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.163208
last_received_ts = 191.162781
last_seq = 6123
current_ts = 191.179886
current_received_ts = 191.179413
current_seq = 6124
count = 503
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 6us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.179886
last_received_ts = 191.179413
last_seq = 6124
current_ts = 191.196548
current_received_ts = 191.196106
current_seq = 6125
count = 504
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.196548
last_received_ts = 191.196106
last_seq = 6125
current_ts = 191.213211
current_received_ts = 191.212875
current_seq = 6126
count = 505
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.213211
last_received_ts = 191.212875
last_seq = 6126
current_ts = 191.229889
current_received_ts = 191.229584
current_seq = 6127
count = 506
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.229889
last_received_ts = 191.229584
last_seq = 6127
current_ts = 191.246552
current_received_ts = 191.246277
current_seq = 6128
count = 507
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.246552
last_received_ts = 191.246277
last_seq = 6128
current_ts = 191.263229
current_received_ts = 191.262939
current_seq = 6129
count = 508
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.263229
last_received_ts = 191.262939
last_seq = 6129
current_ts = 191.279877
current_received_ts = 191.279587
current_seq = 6130
count = 509
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.279877
last_received_ts = 191.279587
last_seq = 6130
current_ts = 191.296555
current_received_ts = 191.296280
current_seq = 6131
count = 510
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.296555
last_received_ts = 191.296280
last_seq = 6131
current_ts = 191.313217
current_received_ts = 191.312881
current_seq = 6132
count = 511
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.313217
last_received_ts = 191.312881
last_seq = 6132
current_ts = 191.329895
current_received_ts = 191.329605
current_seq = 6133
count = 512
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.329895
last_received_ts = 191.329605
last_seq = 6133
current_ts = 191.346558
current_received_ts = 191.346268
current_seq = 6134
count = 513
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.346558
last_received_ts = 191.346268
last_seq = 6134
current_ts = 191.363220
current_received_ts = 191.362930
current_seq = 6135
count = 514
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 15us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.363220
last_received_ts = 191.362930
last_seq = 6135
current_ts = 191.379883
current_received_ts = 191.379425
current_seq = 6136
count = 515
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 6us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.379883
last_received_ts = 191.379425
last_seq = 6136
current_ts = 191.396545
current_received_ts = 191.396103
current_seq = 6137
count = 516
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.396545
last_received_ts = 191.396103
last_seq = 6137
current_ts = 191.413208
current_received_ts = 191.412766
current_seq = 6138
count = 517
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.413208
last_received_ts = 191.412766
last_seq = 6138
current_ts = 191.429871
current_received_ts = 191.429428
current_seq = 6139
count = 518
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.429871
last_received_ts = 191.429428
last_seq = 6139
current_ts = 191.446548
current_received_ts = 191.446136
current_seq = 6140
count = 519
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 7us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.446548
last_received_ts = 191.446136
last_seq = 6140
current_ts = 191.463211
current_received_ts = 191.463715
current_seq = 6141
count = 520
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 13us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.463211
last_received_ts = 191.463715
last_seq = 6141
current_ts = 191.479889
current_received_ts = 191.479309
current_seq = 6142
count = 521
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.479889
last_received_ts = 191.479309
last_seq = 6142
current_ts = 191.496552
current_received_ts = 191.496033
current_seq = 6143
count = 522
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.496552
last_received_ts = 191.496033
last_seq = 6143
current_ts = 191.513229
current_received_ts = 191.512939
current_seq = 6144
count = 523
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.513229
last_received_ts = 191.512939
last_seq = 6144
current_ts = 191.529892
current_received_ts = 191.529617
current_seq = 6145
count = 524
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 12us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.529892
last_received_ts = 191.529617
last_seq = 6145
current_ts = 191.546555
current_received_ts = 191.546326
current_seq = 6146
count = 525
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 14us
(kms_flip:2382) DEBUG: name = flip
last_ts = 191.546555
last_received_ts = 191.546326
last_seq = 6146
current_ts = 191.563217
current_received_ts = 191.562958
current_seq = 6147
count = 526
seq_step = 1
(kms_flip:2382) DEBUG: Vblank took 525us
(kms_flip:2382) CRITICAL: Test assertion failure function run_test_step, file ../tests/kms_flip.c:931:
(kms_flip:2382) CRITICAL: Failed assertion: end - start < 500
(kms_flip:2382) igt_core-INFO: Stack trace:
(kms_flip:2382) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2382) igt_core-INFO:   #1 ../tests/kms_flip.c:926 run_test_on_crtc_set()
(kms_flip:2382) igt_core-INFO:   #2 ../tests/kms_flip.c:2029 run_pair()
(kms_flip:2382) igt_core-INFO:   #3 ../tests/kms_flip.c:2193 __igt_unique____real_main2105()
(kms_flip:2382) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2382) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2382) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2382) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest AC-HDMI-A1-HDMI-A2: FAIL (9.905s)
Dmesg
<6> [182.112030] [IGT] kms_flip: starting dynamic subtest AC-HDMI-A1-HDMI-A2
<7> [182.116055] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:186]
<7> [182.117146] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:187]
<7> [182.179585] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [182.179826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [182.180273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [182.180753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [182.182965] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [182.183413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [182.183991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [182.184378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [182.186528] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [182.187011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [182.187391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [182.187813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [182.190870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [182.191341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [182.192266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [182.194700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [182.195378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [182.197557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [182.198030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [182.198409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [182.200854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [182.201326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [182.202267] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [182.204363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [182.205741] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [182.206189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [182.208653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [182.209122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [182.211326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [182.212268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [182.213992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [182.215749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [182.216232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [182.218467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [182.220574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [182.221050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [182.221430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [182.221858] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [182.226620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [182.227092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [182.227511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [182.227892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [182.230241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [182.230762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [182.231144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.234844] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.234856] i915 0000:00:02.0: colorspace: RGB
<7> [182.234860] i915 0000:00:02.0: scan mode: Underscan
<7> [182.234864] i915 0000:00:02.0: colorimetry: No Data
<7> [182.234867] i915 0000:00:02.0: picture aspect: No Data
<7> [182.234870] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.234873] i915 0000:00:02.0: itc: No Data
<7> [182.234876] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.234880] i915 0000:00:02.0: quantization range: Full
<7> [182.234883] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.234895] i915 0000:00:02.0: video code: 0
<7> [182.234898] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.234901] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.234905] i915 0000:00:02.0: pixel repeat: 0
<7> [182.234908] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.234912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.235380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [182.237532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.238005] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.238010] i915 0000:00:02.0: vendor: Intel
<7> [182.238578] i915 0000:00:02.0: product: Integrated gfx
<7> [182.238584] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.238589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.239078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [182.239456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.239864] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.239869] i915 0000:00:02.0: empty frame
<7> [182.239872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.244124] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [182.246557] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:78:pipe A] releasing PORT PLL B
<7> [182.247117] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x1 -> 0x0, ddb (0 - 510) -> (0 - 0), active pipes 0x3 -> 0x2
<7> [182.247830] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x1, ddb (510 - 1020) -> (0 - 1020), active pipes 0x3 -> 0x2
<7> [182.248290] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 497) -> ( 0 - 0), size 497 -> 0
<7> [182.251531] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 497 - 510) -> ( 0 - 0), size 13 -> 0
<7> [182.252179] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [182.253346] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.253784] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.254157] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.257528] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.258006] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:79:plane 1B] ddb ( 510 - 1007) -> ( 0 - 988), size 497 -> 988
<7> [182.258379] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb (1007 - 1020) -> ( 988 - 1020), size 13 -> 32
<7> [182.258847] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [182.261394] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 18563 kHz -> 9282 kHz
<7> [182.263337] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: no [modeset]
<7> [182.263768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [NOFB], visible: no
<7> [182.264149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [182.266367] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [182.267666] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [182.268057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [182.270928] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Disable audio codec on [CRTC:78:pipe A]
<7> [182.292008] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [182.305144] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [182.309557] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [182.326516] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [182.433611] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [182.434170] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL B (active 0x1, on? 1) for [CRTC:78:pipe A]
<7> [182.437750] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL B
<7> [182.438251] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:36:plane 1A]
<7> [182.439228] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [182.439813] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [182.440194] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [182.450167] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-b
<7> [182.450740] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [182.452310] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:121:pipe B]
<7> [182.452623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [182.453101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [182.453492] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [182.454451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [182.455229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in lane_lat_optim_mask (expected 0x0000000d, found 0x00000000)
<7> [182.456812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [182.457777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [182.458252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [182.458968] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [182.459378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [182.460832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [182.461286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [182.461874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [182.462262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [182.462939] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [182.463376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [182.464007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [182.464434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [182.465936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [182.466748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [182.467228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [182.468012] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [182.468440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [182.469570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [182.469986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [182.470975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [182.471852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [182.472315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [182.472970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [182.473383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [182.474692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [182.475161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [182.475904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [182.476421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [182.477213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [182.477748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [182.478170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in pipe_bpp (expected 36, found 0)
<7> [182.478701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [182.479093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [182.480572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in port_clock (expected 222750, found 0)
<7> [182.481095] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [182.481777] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in infoframes.gcp (expected 0x00000006, found 0x00000000)
<7> [182.482225] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in avi infoframe
<7> [182.482834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.483244] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.483249] i915 0000:00:02.0: colorspace: RGB
<7> [182.483253] i915 0000:00:02.0: scan mode: Underscan
<7> [182.483256] i915 0000:00:02.0: colorimetry: No Data
<7> [182.483260] i915 0000:00:02.0: picture aspect: No Data
<7> [182.483263] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.483266] i915 0000:00:02.0: itc: No Data
<7> [182.483269] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.483272] i915 0000:00:02.0: quantization range: Full
<7> [182.483275] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.483278] i915 0000:00:02.0: video code: 0
<7> [182.483281] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.483284] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.483288] i915 0000:00:02.0: pixel repeat: 0
<7> [182.483291] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.483294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.483833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in spd infoframe
<7> [182.484235] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.486103] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.486109] i915 0000:00:02.0: vendor: Intel
<7> [182.486113] i915 0000:00:02.0: product: Integrated gfx
<7> [182.486116] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.486120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.486894] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:121:pipe B] fastset requirement not met in hdmi infoframe
<7> [182.487280] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.487976] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.487981] i915 0000:00:02.0: empty frame
<7> [182.487985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.488424] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:121:pipe B] fastset requirement not met, forcing full modeset
<7> [182.489016] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:121:pipe B] releasing PORT PLL C
<7> [182.489648] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:121:pipe B] dbuf slices 0x1 -> 0x0, ddb (0 - 1020) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [182.490115] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:79:plane 1B] ddb ( 0 - 988) -> ( 0 - 0), size 988 -> 0
<7> [182.491526] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:115:cursor B] ddb ( 988 - 1020) -> ( 0 - 0), size 32 -> 0
<7> [182.491985] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:79:plane 1B] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [182.492362] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:79:plane 1B] lines 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.493417] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:79:plane 1B] blocks 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.494298] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:79:plane 1B] min_ddb 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.495019] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:79:plane 1B] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.495625] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:121:pipe B] min cdclk: 192000 kHz -> 0 kHz
<7> [182.496051] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [182.496706] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [182.497089] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 79200 kHz, actual 79200 kHz
<7> [182.498538] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 4, actual 4
<7> [182.499181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:121:pipe B] enable: no [modeset]
<7> [182.499847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:79:plane 1B] fb: [NOFB], visible: no
<7> [182.500301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:88:plane 2B] fb: [NOFB], visible: no
<7> [182.500866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:97:plane 3B] fb: [NOFB], visible: no
<7> [182.501270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:106:plane 4B] fb: [NOFB], visible: no
<7> [182.501812] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:115:cursor B] fb: [NOFB], visible: no
<7> [182.502414] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Disable audio codec on [CRTC:121:pipe B]
<7> [182.533393] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port C
<7> [182.535496] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [182.551534] i915 0000:00:02.0: [drm:intel_ddi_disable_transcoder_func [i915]] Quirk Increase DDI disabled time
<7> [182.657597] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_C
<7> [182.658117] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable PORT PLL C (active 0x2, on? 1) for [CRTC:121:pipe B]
<7> [182.658746] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling PORT PLL C
<7> [182.659184] 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> [182.659636] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [182.660023] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [182.660441] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [182.661978] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling dpio-common-c
<7> [182.664097] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:121:pipe B]
<7> [182.665459] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [182.665323] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [182.665775] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 316800 kHz, actual 316800 kHz
<7> [182.666153] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 13, actual 13
<7> [182.667814] 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> [182.669222] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:164:pipe C]
<7> [182.669289] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:176:HDMI-A-2]
<7> [182.669522] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [182.670053] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [182.672806] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:175:DDI C/PHY C] [CRTC:164:pipe C]
<7> [182.673290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [182.673701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [182.674238] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [182.674254] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<7> [182.674082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [182.676020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [182.676527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [182.676909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [182.677290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [182.679738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [182.680293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [182.680733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [182.681695] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [182.682109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [182.682678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [182.683065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [182.686108] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [182.686634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [182.687019] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [182.687399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [182.689311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [182.689867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [182.690252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [182.691289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [182.691915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [182.692308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [182.692721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [182.693101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [182.693518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [182.693972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [182.694359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [182.694769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [182.695174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [182.695727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [182.696112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [182.696517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [182.696926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [182.697372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [182.697802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [182.698183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [182.698640] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [182.699022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [182.699439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [182.699873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [182.700293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in avi infoframe
<7> [182.700693] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.701071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.701518] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.701524] i915 0000:00:02.0: colorspace: RGB
<7> [182.701527] i915 0000:00:02.0: scan mode: Underscan
<7> [182.701531] i915 0000:00:02.0: colorimetry: No Data
<7> [182.701534] i915 0000:00:02.0: picture aspect: No Data
<7> [182.701537] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.701540] i915 0000:00:02.0: itc: No Data
<7> [182.701543] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.701547] i915 0000:00:02.0: quantization range: Full
<7> [182.701550] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.701553] i915 0000:00:02.0: video code: 0
<7> [182.701556] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.701559] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.701563] i915 0000:00:02.0: pixel repeat: 0
<7> [182.701566] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.701569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in spd infoframe
<7> [182.701950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.702330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.702730] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.702735] i915 0000:00:02.0: vendor: Intel
<7> [182.702739] i915 0000:00:02.0: product: Integrated gfx
<7> [182.702742] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.702746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:164:pipe C] fastset requirement not met in hdmi infoframe
<7> [182.703133] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.703539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.703918] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.703922] i915 0000:00:02.0: empty frame
<7> [182.703928] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:164:pipe C] fastset requirement not met, forcing full modeset
<7> [182.704424] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 1020), active pipes 0x0 -> 0x4
<7> [182.704885] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 0) -> ( 0 - 988), size 0 -> 988
<7> [182.705263] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 0 - 0) -> ( 988 - 1020), size 0 -> 32
<7> [182.705656] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [182.706054] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [182.706428] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [182.706819] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [182.707192] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:122:plane 1C] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.707639] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:164:pipe C] min cdclk: 0 kHz -> 192000 kHz
<7> [182.708074] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [182.708455] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:164:pipe C] using pre-allocated PORT PLL C
<7> [182.709219] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:164:pipe C] reserving PORT PLL C
<7> [182.709669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:164:pipe C] enable: yes [modeset]
<7> [182.710052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [182.710441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: C, pipe bpp: 36, dithering: 0
<7> [182.710866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [182.711243] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [182.711735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [182.712119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [182.712540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [182.712919] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [182.713300] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.713305] i915 0000:00:02.0: colorspace: RGB
<7> [182.713308] i915 0000:00:02.0: scan mode: Underscan
<7> [182.713312] i915 0000:00:02.0: colorimetry: No Data
<7> [182.713315] i915 0000:00:02.0: picture aspect: No Data
<7> [182.713318] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.713321] i915 0000:00:02.0: itc: No Data
<7> [182.713324] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.713328] i915 0000:00:02.0: quantization range: Full
<7> [182.713331] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.713334] i915 0000:00:02.0: video code: 0
<7> [182.713337] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.713340] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.713343] i915 0000:00:02.0: pixel repeat: 0
<7> [182.713346] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.713350] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.713354] i915 0000:00:02.0: vendor: Intel
<7> [182.713357] i915 0000:00:02.0: product: Integrated gfx
<7> [182.713360] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.713364] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.713368] i915 0000:00:02.0: empty frame
<7> [182.713371] 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> [182.713882] 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> [182.714332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [182.714750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [182.715128] 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> [182.715557] 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> [182.715972] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [182.716355] 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> [182.716766] 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> [182.717171] 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> [182.717584] 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> [182.717964] 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> [182.718342] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [182.718737] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [182.719117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 1, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [182.719519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [182.719895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [182.720271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [182.720675] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [182.721057] 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> [182.721434] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [182.721842] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [182.722220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [182.722634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.723011] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.723417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.723808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [182.724187] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:122:plane 1C] fb: [FB:186] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [182.724591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [182.724977] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [182.725441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:131:plane 2C] fb: [NOFB], visible: no
<7> [182.725848] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:140:plane 3C] fb: [NOFB], visible: no
<7> [182.726251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:149:plane 4C] fb: [NOFB], visible: no
<7> [182.726657] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:158:cursor C] fb: [NOFB], visible: no
<7> [182.727723] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-c
<7> [182.729692] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [182.730187] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [182.730757] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL C (active 0x4, on? 0) for [CRTC:164:pipe C]
<7> [182.731199] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL C
<7> [182.732258] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_C
<7> [182.732902] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe C
<7> [182.769900] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:176:HDMI-A-2][ENCODER:175:DDI C/PHY C] Enable audio codec on [CRTC:164:pipe C], 32 bytes ELD
<7> [182.785776] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [182.786265] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [182.789738] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:176:HDMI-A-2]
<7> [182.792031] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:164:pipe C]
<7> [182.796740] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:78:pipe A]
<7> [182.796808] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:166:HDMI-A-1]
<7> [182.797003] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 12 bpc for HDMI output (pipe bpp: 36)
<7> [182.798582] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] hw max bpp: 36, pipe bpp: 36, dithering: 0
<7> [182.799055] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:165:DDI B/PHY B] [CRTC:78:pipe A]
<7> [182.799434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [182.802004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [182.803261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [182.803722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [182.804104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in lane_lat_optim_mask (expected 0x00000000, found 0x0000000d)
<7> [182.805957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [182.807886] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [182.808358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [182.808823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [182.810284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [182.810775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [182.811157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [182.811590] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [182.812004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [182.812384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [182.812787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [182.813191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [182.813610] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [182.813989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [182.814366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [182.814778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [182.815159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [182.815566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [182.815945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [182.816347] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [182.816813] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [182.817195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [182.817601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [182.818002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [182.818383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [182.818786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [182.819165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [182.819566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [182.819944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [182.820322] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [182.820744] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [182.821147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 36)
<7> [182.821555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [182.821934] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [182.822310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in port_clock (expected 0, found 222750)
<7> [182.822711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [182.823157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in infoframes.gcp (expected 0x00000000, found 0x00000006)
<7> [182.823602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in avi infoframe
<7> [182.823983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.824410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.824838] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.824843] i915 0000:00:02.0: colorspace: RGB
<7> [182.824847] i915 0000:00:02.0: scan mode: Underscan
<7> [182.824850] i915 0000:00:02.0: colorimetry: No Data
<7> [182.824853] i915 0000:00:02.0: picture aspect: No Data
<7> [182.824856] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.824860] i915 0000:00:02.0: itc: No Data
<7> [182.824863] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.824866] i915 0000:00:02.0: quantization range: Full
<7> [182.824869] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.824872] i915 0000:00:02.0: video code: 0
<7> [182.824875] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.824878] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.824881] i915 0000:00:02.0: pixel repeat: 0
<7> [182.824884] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.824888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in spd infoframe
<7> [182.825268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.825680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.826090] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.826094] i915 0000:00:02.0: vendor: Intel
<7> [182.826098] i915 0000:00:02.0: product: Integrated gfx
<7> [182.826101] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.826105] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:78:pipe A] fastset requirement not met in hdmi infoframe
<7> [182.826508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [182.826887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [182.827289] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.827294] i915 0000:00:02.0: empty frame
<7> [182.827299] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:78:pipe A] fastset requirement not met, forcing full modeset
<7> [182.827794] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:78:pipe A] dbuf slices 0x0 -> 0x1, ddb (0 - 0) -> (0 - 510), active pipes 0x4 -> 0x5
<7> [182.828204] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:164:pipe C] dbuf slices 0x1 -> 0x1, ddb (0 - 1020) -> (510 - 1020), active pipes 0x4 -> 0x5
<7> [182.828728] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:36:plane 1A] ddb ( 0 - 0) -> ( 0 - 497), size 0 -> 497
<7> [182.829103] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:72:cursor A] ddb ( 0 - 0) -> ( 497 - 510), size 0 -> 13
<7> [182.829490] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [182.829864] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 1, 1, 2, 2, 2, 2, 2, 0, 0, 0
<7> [182.830245] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 16, 16, 33, 33, 33, 33, 33, 30, 0, 0
<7> [182.830648] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 17, 17, 17, 34, 34, 34, 34, 34, 31, 0, 0
<7> [182.831068] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:36:plane 1A] min_ddb_uv 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [182.831446] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:122:plane 1C] ddb ( 0 - 988) -> ( 510 - 1007), size 988 -> 497
<7> [182.831899] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:158:cursor C] ddb ( 988 - 1020) -> (1007 - 1020), size 32 -> 13
<7> [182.832328] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:78:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [182.832816] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 18563 kHz
<7> [182.833199] i915 0000:00:02.0: [drm:bxt_get_dpll [i915]] [CRTC:78:pipe A] using pre-allocated PORT PLL B
<7> [182.833637] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:78:pipe A] reserving PORT PLL B
<7> [182.834099] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:78:pipe A] enable: yes [modeset]
<7> [182.834559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [182.834962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 36, dithering: 0
<7> [182.835385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [182.835805] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [182.836186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [182.836596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [182.837011] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [182.837390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x6
<7> [182.837802] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [182.837807] i915 0000:00:02.0: colorspace: RGB
<7> [182.837810] i915 0000:00:02.0: scan mode: Underscan
<7> [182.837814] i915 0000:00:02.0: colorimetry: No Data
<7> [182.837817] i915 0000:00:02.0: picture aspect: No Data
<7> [182.837820] i915 0000:00:02.0: active aspect: Same as Picture
<7> [182.837823] i915 0000:00:02.0: itc: No Data
<7> [182.837826] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [182.837829] i915 0000:00:02.0: quantization range: Full
<7> [182.837832] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [182.837835] i915 0000:00:02.0: video code: 0
<7> [182.837838] i915 0000:00:02.0: ycc quantization range: Full
<7> [182.837841] i915 0000:00:02.0: hdmi content type: Graphics
<7> [182.837845] i915 0000:00:02.0: pixel repeat: 0
<7> [182.837848] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [182.837851] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [182.837855] i915 0000:00:02.0: vendor: Intel
<7> [182.837858] i915 0000:00:02.0: product: Integrated gfx
<7> [182.837862] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [182.837865] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [182.837868] i915 0000:00:02.0: empty frame
<7> [182.837872] 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> [182.838279] 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> [182.838694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [182.839069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [182.839443] 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> [182.839870] 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> [182.840246] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [182.840655] 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> [182.841032] 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> [182.841419] 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> [182.841819] 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> [182.842197] 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> [182.842597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 222750, pixel rate 148500, min cdclk 192000, min voltage level 0
<7> [182.842997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [182.843373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [182.843773] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [182.844149] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [182.844556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [182.844932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [182.845306] 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> [182.845717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [182.846196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [182.846597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [182.846973] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.847347] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.847769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [182.848145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [182.848532] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:36:plane 1A] fb: [FB:186] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [182.848980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [182.849368] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [182.849789] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:45:plane 2A] fb: [NOFB], visible: no
<7> [182.850166] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:54:plane 3A] fb: [NOFB], visible: no
<7> [182.850601] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [182.850978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:72:cursor A] fb: [NOFB], visible: no
<7> [182.851786] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling dpio-common-b
<7> [182.853618] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:165:DDI B/PHY B]
<7> [182.854124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:175:DDI C/PHY C]
<7> [182.869617] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable PORT PLL B (active 0x1, on? 0) for [CRTC:78:pipe A]
<7> [182.870104] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling PORT PLL B
<7> [182.871699] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [182.872242] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [182.894987] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 16588800 bytes of contiguous stolen space for FBC, limit: 1
<7> [182.895519] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:36:plane 1A]
<7> [182.911709] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:166:HDMI-A-1][ENCODER:165:DDI B/PHY B] Enable audio codec on [CRTC:78:pipe A], 32 bytes ELD
<7> [182.926091] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x10
<7> [182.929493] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 148500 (0x00090000)
<7> [182.929935] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [182.932356] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:166:HDMI-A-1]
<7> [182.936711] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:78:pipe A]
<7> [182.950289] azx_init_pci: snd_hda_intel 0000:00:0e.0: Clearing TCSEL
<7> [182.950310] azx_init_pci: snd_hda_intel 0000:00:0e.0: SCH snoop: Enabled
<6> [192.017202] [IGT] kms_flip: finished subtest AC-HDMI-A1-HDMI-A2, FAIL
Created at 2026-05-05 09:09:50