Results for igt@kms_flip@wf_vblank-ts-check-interruptible@d-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info2 igt_runner2 runtimes2 results2.json results2-i915-load.json guc_logs2.tar i915_display_info_post_exec2 boot2 dmesg2

DetailValue
Duration 18.52 seconds
Hostname 
shard-tglu-3
Igt-Version 
IGT-Version: 2.4-gd28bd0b9e (x86_64) (Linux: 7.1.0-rc3-Patchwork_166289v1-g6cfe03d5832d+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8334.9us +- 3.845us accuracy 0.14%
Event vblank: expected 902, counted 890, passrate = 39.33%, encoder type 2
Expected frametime: 8335us; measured 8433.2us +- 3.454us accuracy 0.12%
vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.454us, difference 98.7us (28.6 sigma)
Event vblank: expected 895, counted 900, passrate = 54.44%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A1: FAIL (18.519s)
Subtest wf_vblank-ts-check-interruptible: FAIL (41.920s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2218) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2218) CRITICAL: Failed assertion: !retried
(kms_flip:2218) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 89.922531/881, current 90.007347/891: elapsed=84817.0us expected=83348.8us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 90.007347
last_received_ts = 90.007042
last_seq = 891
current_ts = 90.092163
current_received_ts = 90.091797
current_seq = 901
count = 85
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 90.007347/891, current 90.092163/901: elapsed=84815.0us expected=83348.8us +- 416.7us, error 1.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 90.007347/891, current 90.092163/901: elapsed=84815.0us expected=83348.8us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 90.092163
last_received_ts = 90.091797
last_seq = 901
current_ts = 90.176979
current_received_ts = 90.176735
current_seq = 911
count = 86
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 90.092163/901, current 90.176979/911: elapsed=84816.0us expected=83348.8us +- 416.7us, error 1.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 90.092163/901, current 90.176979/911: elapsed=84816.0us expected=83348.8us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 90.176979
last_received_ts = 90.176735
last_seq = 911
current_ts = 90.261795
current_received_ts = 90.261467
current_seq = 921
count = 87
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 90.176979/911, current 90.261795/921: elapsed=84812.0us expected=83348.8us +- 416.7us, error 1.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 90.176979/911, current 90.261795/921: elapsed=84812.0us expected=83348.8us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 90.261795
last_received_ts = 90.261467
last_seq = 921
current_ts = 90.346603
current_received_ts = 90.346214
current_seq = 931
count = 88
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 90.261795/921, current 90.346603/931: elapsed=84814.0us expected=83348.8us +- 416.7us, error 1.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 90.261795/921, current 90.346603/931: elapsed=84814.0us expected=83348.8us
(kms_flip:2218) INFO: Event vblank: expected 902, counted 890, passrate = 39.33%, encoder type 2
(kms_flip:2218) DEBUG: dropped frames, expected 902, counted 890, passrate = 39.33%, encoder type 2
(kms_flip:2218) DEBUG: Retrying without a hotplug event
(kms_flip:2218) DEBUG: No stale events found
(kms_flip:2218) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2218) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2218) INFO: Expected frametime: 8335us; measured 8433.2us +- 3.454us accuracy 0.12%
(kms_flip:2218) INFO: vblank interval differs from modeline! expected 8334.6us, measured 8433us +- 3.454us, difference 98.7us (28.6 sigma)
(kms_flip:2218) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 93.704674
current_received_ts = 93.704353
current_seq = 1328
count = 0
seq_step = 10
(kms_flip:2218) DEBUG: name = vblank
last_ts = 93.704674
last_received_ts = 93.704353
last_seq = 1328
current_ts = 93.788948
current_received_ts = 93.788582
current_seq = 1338
count = 1
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 93.704674/1328, current 93.788948/1338: elapsed=84272.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 93.788948
last_received_ts = 93.788582
last_seq = 1338
current_ts = 93.873161
current_received_ts = 93.872910
current_seq = 1348
count = 2
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 93.788948/1338, current 93.873161/1348: elapsed=84210.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 93.873161
last_received_ts = 93.872910
last_seq = 1348
current_ts = 93.957359
current_received_ts = 93.957039
current_seq = 1358
count = 3
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 93.873161/1348, current 93.957359/1358: elapsed=84202.0us expected=84332.5us +- 421.7us, error 0.2%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 93.957359
last_received_ts = 93.957039
last_seq = 1358
current_ts = 94.041573
current_received_ts = 94.041283
current_seq = 1368
count = 4
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 93.957359/1358, current 94.041573/1368: elapsed=84209.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.041573
last_received_ts = 94.041283
last_seq = 1368
current_ts = 94.125778
current_received_ts = 94.125443
current_seq = 1378
count = 5
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.041573/1368, current 94.125778/1378: elapsed=84208.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.125778
last_received_ts = 94.125443
last_seq = 1378
current_ts = 94.209984
current_received_ts = 94.209694
current_seq = 1388
count = 6
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.125778/1378, current 94.209984/1388: elapsed=84205.0us expected=84332.5us +- 421.7us, error 0.2%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.209984
last_received_ts = 94.209694
last_seq = 1388
current_ts = 94.294189
current_received_ts = 94.293846
current_seq = 1398
count = 7
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.209984/1388, current 94.294189/1398: elapsed=84209.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.294189
last_received_ts = 94.293846
last_seq = 1398
current_ts = 94.378403
current_received_ts = 94.378059
current_seq = 1408
count = 8
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.294189/1398, current 94.378403/1408: elapsed=84207.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.378403
last_received_ts = 94.378059
last_seq = 1408
current_ts = 94.462601
current_received_ts = 94.462303
current_seq = 1418
count = 9
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.378403/1408, current 94.462601/1418: elapsed=84202.0us expected=84332.5us +- 421.7us, error 0.2%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.462601
last_received_ts = 94.462303
last_seq = 1418
current_ts = 94.546806
current_received_ts = 94.546524
current_seq = 1428
count = 10
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.462601/1418, current 94.546806/1428: elapsed=84208.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.546806
last_received_ts = 94.546524
last_seq = 1428
current_ts = 94.631020
current_received_ts = 94.630684
current_seq = 1438
count = 11
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.546806/1428, current 94.631020/1438: elapsed=84210.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.631020
last_received_ts = 94.630684
last_seq = 1438
current_ts = 94.715225
current_received_ts = 94.714973
current_seq = 1448
count = 12
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.631020/1438, current 94.715225/1448: elapsed=84207.0us expected=84332.5us +- 421.7us, error 0.1%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.715225
last_received_ts = 94.714973
last_seq = 1448
current_ts = 94.799362
current_received_ts = 94.799042
current_seq = 1458
count = 13
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.715225/1448, current 94.799362/1458: elapsed=84137.0us expected=84332.5us +- 421.7us, error 0.2%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.799362
last_received_ts = 94.799042
last_seq = 1458
current_ts = 94.883461
current_received_ts = 94.883194
current_seq = 1468
count = 14
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.799362/1458, current 94.883461/1468: elapsed=84099.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.883461
last_received_ts = 94.883194
last_seq = 1468
current_ts = 94.967567
current_received_ts = 94.967278
current_seq = 1478
count = 15
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.883461/1468, current 94.967567/1478: elapsed=84106.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 94.967567
last_received_ts = 94.967278
last_seq = 1478
current_ts = 95.051666
current_received_ts = 95.051331
current_seq = 1488
count = 16
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 94.967567/1478, current 95.051666/1488: elapsed=84095.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.051666
last_received_ts = 95.051331
last_seq = 1488
current_ts = 95.135765
current_received_ts = 95.135506
current_seq = 1498
count = 17
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.051666/1488, current 95.135765/1498: elapsed=84100.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.135765
last_received_ts = 95.135506
last_seq = 1498
current_ts = 95.219864
current_received_ts = 95.219513
current_seq = 1508
count = 18
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.135765/1498, current 95.219864/1508: elapsed=84100.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.219864
last_received_ts = 95.219513
last_seq = 1508
current_ts = 95.303963
current_received_ts = 95.303719
current_seq = 1518
count = 19
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.219864/1508, current 95.303963/1518: elapsed=84100.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.303963
last_received_ts = 95.303719
last_seq = 1518
current_ts = 95.388062
current_received_ts = 95.387718
current_seq = 1528
count = 20
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.303963/1518, current 95.388062/1528: elapsed=84099.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.388062
last_received_ts = 95.387718
last_seq = 1528
current_ts = 95.472153
current_received_ts = 95.471855
current_seq = 1538
count = 21
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.388062/1528, current 95.472153/1538: elapsed=84093.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.472153
last_received_ts = 95.471855
last_seq = 1538
current_ts = 95.556252
current_received_ts = 95.555916
current_seq = 1548
count = 22
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.472153/1538, current 95.556252/1548: elapsed=84100.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.556252
last_received_ts = 95.555916
last_seq = 1548
current_ts = 95.640358
current_received_ts = 95.639992
current_seq = 1558
count = 23
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.556252/1548, current 95.640358/1558: elapsed=84103.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.640358
last_received_ts = 95.639992
last_seq = 1558
current_ts = 95.724457
current_received_ts = 95.724190
current_seq = 1568
count = 24
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.640358/1558, current 95.724457/1568: elapsed=84098.0us expected=84332.5us +- 421.7us, error 0.3%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.724457
last_received_ts = 95.724190
last_seq = 1568
current_ts = 95.808487
current_received_ts = 95.808182
current_seq = 1578
count = 25
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.724457/1568, current 95.808487/1578: elapsed=84034.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.808487
last_received_ts = 95.808182
last_seq = 1578
current_ts = 95.892494
current_received_ts = 95.892159
current_seq = 1588
count = 26
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.808487/1578, current 95.892494/1588: elapsed=84002.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.892494
last_received_ts = 95.892159
last_seq = 1588
current_ts = 95.976494
current_received_ts = 95.976227
current_seq = 1598
count = 27
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.892494/1588, current 95.976494/1598: elapsed=84004.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 95.976494
last_received_ts = 95.976227
last_seq = 1598
current_ts = 96.060501
current_received_ts = 96.060158
current_seq = 1608
count = 28
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 95.976494/1598, current 96.060501/1608: elapsed=84006.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.060501
last_received_ts = 96.060158
last_seq = 1608
current_ts = 96.144508
current_received_ts = 96.144196
current_seq = 1618
count = 29
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.060501/1608, current 96.144508/1618: elapsed=84005.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.144508
last_received_ts = 96.144196
last_seq = 1618
current_ts = 96.228508
current_received_ts = 96.228119
current_seq = 1628
count = 30
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.144508/1618, current 96.228508/1628: elapsed=84001.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.228508
last_received_ts = 96.228119
last_seq = 1628
current_ts = 96.312523
current_received_ts = 96.312225
current_seq = 1638
count = 31
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.228508/1628, current 96.312523/1638: elapsed=84013.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.312523
last_received_ts = 96.312225
last_seq = 1638
current_ts = 96.396523
current_received_ts = 96.396133
current_seq = 1648
count = 32
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.312523/1638, current 96.396523/1648: elapsed=83999.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.396523
last_received_ts = 96.396133
last_seq = 1648
current_ts = 96.480522
current_received_ts = 96.480209
current_seq = 1658
count = 33
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.396523/1648, current 96.480522/1658: elapsed=84005.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.480522
last_received_ts = 96.480209
last_seq = 1658
current_ts = 96.564529
current_received_ts = 96.564217
current_seq = 1668
count = 34
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.480522/1658, current 96.564529/1668: elapsed=84007.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.564529
last_received_ts = 96.564217
last_seq = 1668
current_ts = 96.648537
current_received_ts = 96.648224
current_seq = 1678
count = 35
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.564529/1668, current 96.648537/1678: elapsed=84004.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.648537
last_received_ts = 96.648224
last_seq = 1678
current_ts = 96.732536
current_received_ts = 96.732216
current_seq = 1688
count = 36
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.648537/1678, current 96.732536/1688: elapsed=84003.0us expected=84332.5us +- 421.7us, error 0.4%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.732536
last_received_ts = 96.732216
last_seq = 1688
current_ts = 96.816483
current_received_ts = 96.816101
current_seq = 1698
count = 37
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.732536/1688, current 96.816483/1698: elapsed=83940.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.816483
last_received_ts = 96.816101
last_seq = 1698
current_ts = 96.900398
current_received_ts = 96.900024
current_seq = 1708
count = 38
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.816483/1698, current 96.900398/1708: elapsed=83921.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.900398
last_received_ts = 96.900024
last_seq = 1708
current_ts = 96.984329
current_received_ts = 96.983948
current_seq = 1718
count = 39
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.900398/1708, current 96.984329/1718: elapsed=83927.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 96.984329
last_received_ts = 96.983948
last_seq = 1718
current_ts = 97.068245
current_received_ts = 97.067848
current_seq = 1728
count = 40
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 96.984329/1718, current 97.068245/1728: elapsed=83917.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.068245
last_received_ts = 97.067848
last_seq = 1728
current_ts = 97.152168
current_received_ts = 97.151787
current_seq = 1738
count = 41
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.068245/1728, current 97.152168/1738: elapsed=83928.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.152168
last_received_ts = 97.151787
last_seq = 1738
current_ts = 97.236092
current_received_ts = 97.235809
current_seq = 1748
count = 42
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.152168/1738, current 97.236092/1748: elapsed=83919.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.236092
last_received_ts = 97.235809
last_seq = 1748
current_ts = 97.320007
current_received_ts = 97.319656
current_seq = 1758
count = 43
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.236092/1748, current 97.320007/1758: elapsed=83920.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.320007
last_received_ts = 97.319656
last_seq = 1758
current_ts = 97.403938
current_received_ts = 97.403656
current_seq = 1768
count = 44
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.320007/1758, current 97.403938/1768: elapsed=83930.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.403938
last_received_ts = 97.403656
last_seq = 1768
current_ts = 97.487862
current_received_ts = 97.487465
current_seq = 1778
count = 45
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.403938/1768, current 97.487862/1778: elapsed=83921.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.487862
last_received_ts = 97.487465
last_seq = 1778
current_ts = 97.571777
current_received_ts = 97.571465
current_seq = 1788
count = 46
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.487862/1778, current 97.571777/1788: elapsed=83918.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.571777
last_received_ts = 97.571465
last_seq = 1788
current_ts = 97.655701
current_received_ts = 97.655342
current_seq = 1798
count = 47
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.571777/1788, current 97.655701/1798: elapsed=83921.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.655701
last_received_ts = 97.655342
last_seq = 1798
current_ts = 97.739624
current_received_ts = 97.739250
current_seq = 1808
count = 48
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.655701/1798, current 97.739624/1808: elapsed=83925.0us expected=84332.5us +- 421.7us, error 0.5%
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.739624
last_received_ts = 97.739250
last_seq = 1808
current_ts = 97.823486
current_received_ts = 97.823105
current_seq = 1818
count = 49
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.739624/1808, current 97.823486/1818: elapsed=83861.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 97.739624/1808, current 97.823486/1818: elapsed=83861.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.823486
last_received_ts = 97.823105
last_seq = 1818
current_ts = 97.907341
current_received_ts = 97.906937
current_seq = 1828
count = 50
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.823486/1818, current 97.907341/1828: elapsed=83851.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 97.823486/1818, current 97.907341/1828: elapsed=83851.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.907341
last_received_ts = 97.906937
last_seq = 1828
current_ts = 97.991188
current_received_ts = 97.990799
current_seq = 1838
count = 51
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.907341/1828, current 97.991188/1838: elapsed=83847.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 97.907341/1828, current 97.991188/1838: elapsed=83847.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 97.991188
last_received_ts = 97.990799
last_seq = 1838
current_ts = 98.075035
current_received_ts = 98.074646
current_seq = 1848
count = 52
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 97.991188/1838, current 98.075035/1848: elapsed=83850.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 97.991188/1838, current 98.075035/1848: elapsed=83850.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.075035
last_received_ts = 98.074646
last_seq = 1848
current_ts = 98.158890
current_received_ts = 98.158516
current_seq = 1858
count = 53
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.075035/1848, current 98.158890/1858: elapsed=83854.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.075035/1848, current 98.158890/1858: elapsed=83854.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.158890
last_received_ts = 98.158516
last_seq = 1858
current_ts = 98.242737
current_received_ts = 98.242348
current_seq = 1868
count = 54
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.158890/1858, current 98.242737/1868: elapsed=83846.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.158890/1858, current 98.242737/1868: elapsed=83846.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.242737
last_received_ts = 98.242348
last_seq = 1868
current_ts = 98.326591
current_received_ts = 98.326294
current_seq = 1878
count = 55
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.242737/1868, current 98.326591/1878: elapsed=83855.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.242737/1868, current 98.326591/1878: elapsed=83855.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.326591
last_received_ts = 98.326294
last_seq = 1878
current_ts = 98.410439
current_received_ts = 98.410057
current_seq = 1888
count = 56
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.326591/1878, current 98.410439/1888: elapsed=83851.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.326591/1878, current 98.410439/1888: elapsed=83851.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.410439
last_received_ts = 98.410057
last_seq = 1888
current_ts = 98.494286
current_received_ts = 98.493919
current_seq = 1898
count = 57
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.410439/1888, current 98.494286/1898: elapsed=83844.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.410439/1888, current 98.494286/1898: elapsed=83844.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.494286
last_received_ts = 98.493919
last_seq = 1898
current_ts = 98.578140
current_received_ts = 98.577812
current_seq = 1908
count = 58
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.494286/1898, current 98.578140/1908: elapsed=83857.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.494286/1898, current 98.578140/1908: elapsed=83857.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.578140
last_received_ts = 98.577812
last_seq = 1908
current_ts = 98.661995
current_received_ts = 98.661591
current_seq = 1918
count = 59
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.578140/1908, current 98.661995/1918: elapsed=83851.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.578140/1908, current 98.661995/1918: elapsed=83851.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.661995
last_received_ts = 98.661591
last_seq = 1918
current_ts = 98.745842
current_received_ts = 98.745476
current_seq = 1928
count = 60
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.661995/1918, current 98.745842/1928: elapsed=83850.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.661995/1918, current 98.745842/1928: elapsed=83850.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.745842
last_received_ts = 98.745476
last_seq = 1928
current_ts = 98.829628
current_received_ts = 98.829285
current_seq = 1938
count = 61
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.745842/1928, current 98.829628/1938: elapsed=83787.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.745842/1928, current 98.829628/1938: elapsed=83787.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.829628
last_received_ts = 98.829285
last_seq = 1938
current_ts = 98.913414
current_received_ts = 98.913055
current_seq = 1948
count = 62
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.829628/1938, current 98.913414/1948: elapsed=83784.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.829628/1938, current 98.913414/1948: elapsed=83784.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.913414
last_received_ts = 98.913055
last_seq = 1948
current_ts = 98.997208
current_received_ts = 98.996948
current_seq = 1958
count = 63
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.913414/1948, current 98.997208/1958: elapsed=83793.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.913414/1948, current 98.997208/1958: elapsed=83793.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 98.997208
last_received_ts = 98.996948
last_seq = 1958
current_ts = 99.080994
current_received_ts = 99.080711
current_seq = 1968
count = 64
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 98.997208/1958, current 99.080994/1968: elapsed=83783.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 98.997208/1958, current 99.080994/1968: elapsed=83783.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.080994
last_received_ts = 99.080711
last_seq = 1968
current_ts = 99.164772
current_received_ts = 99.164421
current_seq = 1978
count = 65
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.080994/1968, current 99.164772/1978: elapsed=83785.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.080994/1968, current 99.164772/1978: elapsed=83785.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.164772
last_received_ts = 99.164421
last_seq = 1978
current_ts = 99.248566
current_received_ts = 99.248222
current_seq = 1988
count = 66
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.164772/1978, current 99.248566/1988: elapsed=83791.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.164772/1978, current 99.248566/1988: elapsed=83791.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.248566
last_received_ts = 99.248222
last_seq = 1988
current_ts = 99.332359
current_received_ts = 99.332169
current_seq = 1998
count = 67
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.248566/1988, current 99.332359/1998: elapsed=83791.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.248566/1988, current 99.332359/1998: elapsed=83791.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.332359
last_received_ts = 99.332169
last_seq = 1998
current_ts = 99.416145
current_received_ts = 99.415840
current_seq = 2008
count = 68
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.332359/1998, current 99.416145/2008: elapsed=83787.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.332359/1998, current 99.416145/2008: elapsed=83787.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.416145
last_received_ts = 99.415840
last_seq = 2008
current_ts = 99.499931
current_received_ts = 99.499634
current_seq = 2018
count = 69
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.416145/2008, current 99.499931/2018: elapsed=83786.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.416145/2008, current 99.499931/2018: elapsed=83786.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.499931
last_received_ts = 99.499634
last_seq = 2018
current_ts = 99.583717
current_received_ts = 99.583351
current_seq = 2028
count = 70
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.499931/2018, current 99.583717/2028: elapsed=83788.0us expected=84332.5us +- 421.7us, error 0.6%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.499931/2018, current 99.583717/2028: elapsed=83788.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.583717
last_received_ts = 99.583351
last_seq = 2028
current_ts = 99.667503
current_received_ts = 99.667206
current_seq = 2038
count = 71
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.583717/2028, current 99.667503/2038: elapsed=83784.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.583717/2028, current 99.667503/2038: elapsed=83784.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.667503
last_received_ts = 99.667206
last_seq = 2038
current_ts = 99.751289
current_received_ts = 99.750900
current_seq = 2048
count = 72
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.667503/2038, current 99.751289/2048: elapsed=83784.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.667503/2038, current 99.751289/2048: elapsed=83784.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.751289
last_received_ts = 99.750900
last_seq = 2048
current_ts = 99.835022
current_received_ts = 99.834633
current_seq = 2058
count = 73
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.751289/2048, current 99.835022/2058: elapsed=83737.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.751289/2048, current 99.835022/2058: elapsed=83737.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.835022
last_received_ts = 99.834633
last_seq = 2058
current_ts = 99.918755
current_received_ts = 99.918358
current_seq = 2068
count = 74
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.835022/2058, current 99.918755/2068: elapsed=83732.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.835022/2058, current 99.918755/2068: elapsed=83732.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 99.918755
last_received_ts = 99.918358
last_seq = 2068
current_ts = 100.002487
current_received_ts = 100.002098
current_seq = 2078
count = 75
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 99.918755/2068, current 100.002487/2078: elapsed=83729.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 99.918755/2068, current 100.002487/2078: elapsed=83729.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.002487
last_received_ts = 100.002098
last_seq = 2078
current_ts = 100.086212
current_received_ts = 100.085823
current_seq = 2088
count = 76
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.002487/2078, current 100.086212/2088: elapsed=83729.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.002487/2078, current 100.086212/2088: elapsed=83729.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.086212
last_received_ts = 100.085823
last_seq = 2088
current_ts = 100.169952
current_received_ts = 100.169563
current_seq = 2098
count = 77
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.086212/2088, current 100.169952/2098: elapsed=83737.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.086212/2088, current 100.169952/2098: elapsed=83737.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.169952
last_received_ts = 100.169563
last_seq = 2098
current_ts = 100.253677
current_received_ts = 100.253296
current_seq = 2108
count = 78
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.169952/2098, current 100.253677/2108: elapsed=83731.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.169952/2098, current 100.253677/2108: elapsed=83731.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.253677
last_received_ts = 100.253296
last_seq = 2108
current_ts = 100.337410
current_received_ts = 100.337639
current_seq = 2118
count = 79
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.253677/2108, current 100.337410/2118: elapsed=83730.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.253677/2108, current 100.337410/2118: elapsed=83730.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.337410
last_received_ts = 100.337639
last_seq = 2118
current_ts = 100.421143
current_received_ts = 100.420830
current_seq = 2128
count = 80
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.337410/2118, current 100.421143/2128: elapsed=83731.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.337410/2118, current 100.421143/2128: elapsed=83731.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.421143
last_received_ts = 100.420830
last_seq = 2128
current_ts = 100.504875
current_received_ts = 100.504570
current_seq = 2138
count = 81
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.421143/2128, current 100.504875/2138: elapsed=83733.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.421143/2128, current 100.504875/2138: elapsed=83733.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.504875
last_received_ts = 100.504570
last_seq = 2138
current_ts = 100.588608
current_received_ts = 100.588249
current_seq = 2148
count = 82
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.504875/2138, current 100.588608/2148: elapsed=83730.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.504875/2138, current 100.588608/2148: elapsed=83730.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.588608
last_received_ts = 100.588249
last_seq = 2148
current_ts = 100.672340
current_received_ts = 100.671982
current_seq = 2158
count = 83
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.588608/2148, current 100.672340/2158: elapsed=83733.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.588608/2148, current 100.672340/2158: elapsed=83733.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.672340
last_received_ts = 100.671982
last_seq = 2158
current_ts = 100.756065
current_received_ts = 100.755745
current_seq = 2168
count = 84
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.672340/2158, current 100.756065/2168: elapsed=83731.0us expected=84332.5us +- 421.7us, error 0.7%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.672340/2158, current 100.756065/2168: elapsed=83731.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.756065
last_received_ts = 100.755745
last_seq = 2168
current_ts = 100.839752
current_received_ts = 100.839363
current_seq = 2178
count = 85
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.756065/2168, current 100.839752/2178: elapsed=83687.0us expected=84332.5us +- 421.7us, error 0.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.756065/2168, current 100.839752/2178: elapsed=83687.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.839752
last_received_ts = 100.839363
last_seq = 2178
current_ts = 100.923431
current_received_ts = 100.923050
current_seq = 2188
count = 86
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.839752/2178, current 100.923431/2188: elapsed=83678.0us expected=84332.5us +- 421.7us, error 0.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.839752/2178, current 100.923431/2188: elapsed=83678.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 100.923431
last_received_ts = 100.923050
last_seq = 2188
current_ts = 101.007118
current_received_ts = 101.006729
current_seq = 2198
count = 87
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 100.923431/2188, current 101.007118/2198: elapsed=83684.0us expected=84332.5us +- 421.7us, error 0.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 100.923431/2188, current 101.007118/2198: elapsed=83684.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 101.007118
last_received_ts = 101.006729
last_seq = 2198
current_ts = 101.090805
current_received_ts = 101.090408
current_seq = 2208
count = 88
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 101.007118/2198, current 101.090805/2208: elapsed=83690.0us expected=84332.5us +- 421.7us, error 0.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 101.007118/2198, current 101.090805/2208: elapsed=83690.0us expected=84332.5us
(kms_flip:2218) DEBUG: name = vblank
last_ts = 101.090805
last_received_ts = 101.090408
last_seq = 2208
current_ts = 101.174484
current_received_ts = 101.174103
current_seq = 2218
count = 89
seq_step = 10
(kms_flip:2218) DEBUG: vblank ts/seq: last 101.090805/2208, current 101.174484/2218: elapsed=83679.0us expected=84332.5us +- 421.7us, error 0.8%
(kms_flip:2218) DEBUG: inconsistent vblank ts/seq: last 101.090805/2208, current 101.174484/2218: elapsed=83679.0us expected=84332.5us
(kms_flip:2218) INFO: Event vblank: expected 895, counted 900, passrate = 54.44%, encoder type 2
(kms_flip:2218) DEBUG: dropped frames, expected 895, counted 900, passrate = 54.44%, encoder type 2
(kms_flip:2218) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2218) CRITICAL: Failed assertion: !retried
(kms_flip:2218) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2218) igt_core-INFO: Stack trace:
(kms_flip:2218) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2218) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:2218) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2218) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2218) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2218) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2218) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2218) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A1: FAIL (18.519s)
Subtest wf_vblank-ts-check-interruptible: FAIL (41.920s)
(kms_flip:2218) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2218) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2218) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2218) igt_kms-CRITICAL: DPMS property not found on 588
Dmesg
<6> [97.860426] [IGT] kms_flip: starting dynamic subtest D-HDMI-A1
<7> [97.860789] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [97.860883] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:638]
<7> [97.884861] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [97.885040] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [97.885157] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [97.885237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [97.885458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [97.885662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [97.885826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [97.885987] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [97.886145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [97.886303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [97.886466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [97.886623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [97.886781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [97.886941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [97.887096] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [97.887251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [97.887406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [97.887576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [97.887736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [97.887986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [97.888142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [97.888299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [97.888487] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [97.888645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [97.888801] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [97.888958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [97.889115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [97.889270] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [97.889425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [97.889598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [97.889757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [97.889913] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [97.890069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [97.890223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [97.890377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [97.890542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [97.890699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [97.890867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [97.891026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [97.891181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [97.891338] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [97.891502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [97.891657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [97.891816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in avi infoframe
<7> [97.891970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.892124] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [97.892126] i915 0000:00:02.0: colorspace: RGB
<7> [97.892128] i915 0000:00:02.0: scan mode: Underscan
<7> [97.892129] i915 0000:00:02.0: colorimetry: No Data
<7> [97.892131] i915 0000:00:02.0: picture aspect: No Data
<7> [97.892132] i915 0000:00:02.0: active aspect: Same as Picture
<7> [97.892134] i915 0000:00:02.0: itc: No Data
<7> [97.892135] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [97.892136] i915 0000:00:02.0: quantization range: Full
<7> [97.892138] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [97.892139] i915 0000:00:02.0: video code: 0
<7> [97.892141] i915 0000:00:02.0: ycc quantization range: Full
<7> [97.892142] i915 0000:00:02.0: hdmi content type: Graphics
<7> [97.892143] i915 0000:00:02.0: pixel repeat: 0
<7> [97.892145] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [97.892147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.892302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in spd infoframe
<7> [97.892462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.892616] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [97.892618] i915 0000:00:02.0: vendor: Intel
<7> [97.892620] i915 0000:00:02.0: product: Integrated gfx
<7> [97.892621] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [97.892623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.892778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hdmi infoframe
<7> [97.892936] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.893090] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [97.893092] i915 0000:00:02.0: empty frame
<7> [97.893093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.893248] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:447:pipe C] fastset requirement not met, forcing full modeset
<7> [97.893405] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:447:pipe C] releasing DPLL 0
<7> [97.893647] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [97.893804] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:447:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [97.893993] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:441:cursor C] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [97.894168] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:447:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [97.894357] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [97.894529] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [97.894687] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [97.894851] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:447:pipe C] enable: no [modeset]
<7> [97.895014] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:311:plane 1C] fb: [NOFB], visible: no
<7> [97.895175] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:341:plane 2C] fb: [NOFB], visible: no
<7> [97.895332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 3C] fb: [NOFB], visible: no
<7> [97.895497] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:401:plane 4C] fb: [NOFB], visible: no
<7> [97.895653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:411:plane 5C] fb: [NOFB], visible: no
<7> [97.895807] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:421:plane 6C] fb: [NOFB], visible: no
<7> [97.895963] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:431:plane 7C] fb: [NOFB], visible: no
<7> [97.896121] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:441:cursor C] fb: [NOFB], visible: no
<7> [97.896354] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:447:pipe C]
<7> [97.909644] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [97.911473] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [97.912188] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [97.912337] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [97.912874] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [97.913029] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [97.913047] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [97.913219] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [97.918463] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [97.918660] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:447:pipe C]
<7> [97.918853] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [97.919028] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [97.919277] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [97.919471] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [97.919638] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [97.919839] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [97.919996] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [97.920161] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [97.920328] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [97.920520] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [97.920764] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [97.920989] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [97.921182] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [97.921364] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [97.921572] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [97.921757] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [97.921993] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [97.922148] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [97.922309] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [97.922483] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [97.922640] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [97.922841] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [97.922995] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [97.923156] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [97.923500] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [97.923793] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [97.924070] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [97.924336] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [97.924648] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:447:pipe C]
<7> [97.925338] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [97.925632] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [97.925680] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [97.925795] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [97.926045] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [97.926290] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [97.926541] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:585:pipe D]
<7> [97.926758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [97.927004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [97.927242] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [97.927486] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [97.927734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [97.927976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [97.928203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [97.928448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [97.928680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [97.928912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [97.929147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [97.929375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [97.929631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [97.929862] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [97.930093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [97.930331] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [97.930584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [97.930809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [97.931030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [97.931257] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [97.931501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [97.931737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [97.931962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [97.932191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [97.932423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [97.932675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [97.932923] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [97.933166] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [97.933393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [97.933638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [97.933866] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [97.934102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [97.934344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [97.934599] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [97.934835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [97.935074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [97.935306] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [97.935556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [97.935783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [97.936016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [97.936246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [97.936482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.936717] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.936954] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [97.936957] i915 0000:00:02.0: colorspace: RGB
<7> [97.936961] i915 0000:00:02.0: scan mode: Underscan
<7> [97.936963] i915 0000:00:02.0: colorimetry: No Data
<7> [97.936966] i915 0000:00:02.0: picture aspect: No Data
<7> [97.936969] i915 0000:00:02.0: active aspect: Same as Picture
<7> [97.936972] i915 0000:00:02.0: itc: No Data
<7> [97.936975] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [97.936977] i915 0000:00:02.0: quantization range: Full
<7> [97.936980] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [97.936983] i915 0000:00:02.0: video code: 0
<7> [97.936986] i915 0000:00:02.0: ycc quantization range: Full
<7> [97.936988] i915 0000:00:02.0: hdmi content type: Graphics
<7> [97.936991] i915 0000:00:02.0: pixel repeat: 0
<7> [97.936994] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [97.936997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [97.937219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.937446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.937673] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [97.937676] i915 0000:00:02.0: vendor: Intel
<7> [97.937679] i915 0000:00:02.0: product: Integrated gfx
<7> [97.937682] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [97.937686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [97.937916] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [97.938144] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [97.938371] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [97.938374] i915 0000:00:02.0: empty frame
<7> [97.938378] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [97.938679] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [97.938911] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [97.939214] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [97.939457] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [97.939678] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [97.939889] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [97.940111] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [97.940332] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [97.940594] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 1142000 num active planes 1
<7> [97.940860] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [97.941089] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [97.941346] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [97.941669] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [97.941900] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [97.942138] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [97.942377] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [97.942623] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [97.942855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [97.943092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [97.943322] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [97.943598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [97.943626] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [97.943845] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [97.943847] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [97.944021] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [97.944084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [97.944319] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [97.944563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [97.944802] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [97.945043] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [97.945046] i915 0000:00:02.0: colorspace: RGB
<7> [97.945050] i915 0000:00:02.0: scan mode: Underscan
<7> [97.945052] i915 0000:00:02.0: colorimetry: No Data
<7> [97.945055] i915 0000:00:02.0: picture aspect: No Data
<7> [97.945058] i915 0000:00:02.0: active aspect: Same as Picture
<7> [97.945061] i915 0000:00:02.0: itc: No Data
<7> [97.945064] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [97.945067] i915 0000:00:02.0: quantization range: Full
<7> [97.945069] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [97.945072] i915 0000:00:02.0: video code: 0
<7> [97.945075] i915 0000:00:02.0: ycc quantization range: Full
<7> [97.945078] i915 0000:00:02.0: hdmi content type: Graphics
<7> [97.945080] i915 0000:00:02.0: pixel repeat: 0
<7> [97.945083] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [97.945086] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [97.945089] i915 0000:00:02.0: vendor: Intel
<7> [97.945092] i915 0000:00:02.0: product: Integrated gfx
<7> [97.945095] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [97.945098] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [97.945101] i915 0000:00:02.0: empty frame
<7> [97.945104] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [97.945246] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [97.945330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [97.945568] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [97.945792] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [97.946023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [97.946259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [97.946493] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [97.946719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [97.946954] 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> [97.947185] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [97.947423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [97.947684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [97.947919] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [97.948141] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [97.948364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [97.948606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [97.948834] 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> [97.949061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [97.949283] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [97.949539] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [97.949772] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [97.950001] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [97.950241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [97.950490] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [97.950710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [97.950931] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.951161] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.951398] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.951651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [97.951887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [97.952131] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.952355] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.952617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [97.952837] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [97.953067] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [97.953305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [97.953547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [97.953790] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [97.954028] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [97.954252] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [97.954481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [97.954705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [97.954938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [97.955169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [97.956566] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [97.956817] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [97.957581] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [97.958783] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [97.959029] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [97.959289] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [97.959549] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [97.959835] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [97.960175] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [97.960411] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [97.960681] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [97.960915] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [97.961134] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [97.961350] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [97.961593] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [97.961821] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [97.962058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [97.962282] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [97.962512] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [97.962744] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [97.962968] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [97.963193] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [97.963425] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [97.963680] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [97.963896] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [97.964114] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [97.964334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [97.964572] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [97.964803] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [97.965256] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [97.965542] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [97.965779] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [97.966065] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [97.966409] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [97.967310] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [97.967971] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [97.968185] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [97.968814] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [97.969040] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [97.969066] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [97.970582] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [97.975011] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [97.975048] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [97.986132] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:585:pipe D], 64 bytes ELD
<7> [97.994427] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [97.994636] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [97.994814] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [97.994977] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [97.994979] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [97.994981] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [97.994983] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [97.995123] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [97.995390] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [98.058560] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [98.063011] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [98.063045] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [108.523576] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [108.524021] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [108.524328] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [108.524737] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [108.525114] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [108.525943] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [108.527108] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [108.527888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [108.528635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [108.529138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [108.529355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [108.529608] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [108.529848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [108.530088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [108.530327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [108.530585] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [108.530822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [108.531065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [108.531308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [108.531566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [108.531802] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [108.532040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [108.532284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [108.532540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [108.532784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [108.533023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [108.533262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [108.533537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [108.533784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [108.534028] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [108.534271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [108.534532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [108.534770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [108.535011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [108.535253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [108.535512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [108.535754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [108.535995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [108.536237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [108.536495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [108.536735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [108.536972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [108.537212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [108.537465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [108.537703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [108.537941] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.538182] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [108.538186] i915 0000:00:02.0: colorspace: RGB
<7> [108.538189] i915 0000:00:02.0: scan mode: Underscan
<7> [108.538192] i915 0000:00:02.0: colorimetry: No Data
<7> [108.538195] i915 0000:00:02.0: picture aspect: No Data
<7> [108.538198] i915 0000:00:02.0: active aspect: Same as Picture
<7> [108.538201] i915 0000:00:02.0: itc: No Data
<7> [108.538204] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [108.538206] i915 0000:00:02.0: quantization range: Full
<7> [108.538209] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [108.538212] i915 0000:00:02.0: video code: 0
<7> [108.538215] i915 0000:00:02.0: ycc quantization range: Full
<7> [108.538218] i915 0000:00:02.0: hdmi content type: Graphics
<7> [108.538220] i915 0000:00:02.0: pixel repeat: 0
<7> [108.538223] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [108.538227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.538511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [108.538752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.538990] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [108.538993] i915 0000:00:02.0: vendor: Intel
<7> [108.538996] i915 0000:00:02.0: product: Integrated gfx
<7> [108.538999] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [108.539002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.539241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [108.539497] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.539733] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [108.539736] i915 0000:00:02.0: empty frame
<7> [108.539739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.539977] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [108.540215] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [108.540513] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [108.540756] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [108.541056] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [108.541290] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [108.541549] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [108.541786] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [108.542022] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [108.542256] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [108.542548] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [108.542798] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [108.543041] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [108.543316] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [108.543545] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [108.543786] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [108.543992] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [108.544234] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [108.544498] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [108.544735] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [108.544898] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [108.545060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [108.545222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [108.545399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [108.545559] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [108.545716] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [108.545874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [108.546103] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:585:pipe D]
<7> [108.562708] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [108.566940] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [108.567589] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [108.567751] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [108.568427] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [108.568601] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [108.568619] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [108.568806] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [108.571893] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [108.572128] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [108.572335] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [108.572512] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 172800 kHz, VCO 345600 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [108.572773] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [108.573008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [108.573188] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [108.573348] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [108.573508] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [108.573665] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [108.573824] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [108.574002] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [108.574173] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [108.574329] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [108.574485] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [108.574640] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [108.574803] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [108.574970] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [108.575136] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [108.575288] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [108.575494] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [108.575721] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [108.575955] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [108.576184] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [108.576407] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [108.576640] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [108.577122] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [108.577395] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [108.577642] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [108.577926] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [108.578176] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [108.578444] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [108.579101] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [108.579158] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [108.579245] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [108.579426] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [108.579594] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [108.579760] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:585:pipe D]
<7> [108.579979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [108.580196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [108.580358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [108.580522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [108.580684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [108.580925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [108.581161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [108.581400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [108.581636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [108.581904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [108.582147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [108.582375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [108.582603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [108.582839] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [108.583072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [108.583303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [108.583475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [108.583636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [108.583795] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [108.584043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [108.584254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [108.584415] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [108.584572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [108.584730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [108.584904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [108.585075] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [108.585236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [108.585452] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [108.585681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [108.585974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [108.586204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [108.586436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [108.586673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [108.586925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [108.587147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [108.587371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [108.587602] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [108.587842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [108.588076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [108.588303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [108.588533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [108.588708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.588914] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.589073] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [108.589076] i915 0000:00:02.0: colorspace: RGB
<7> [108.589078] i915 0000:00:02.0: scan mode: Underscan
<7> [108.589079] i915 0000:00:02.0: colorimetry: No Data
<7> [108.589081] i915 0000:00:02.0: picture aspect: No Data
<7> [108.589082] i915 0000:00:02.0: active aspect: Same as Picture
<7> [108.589084] i915 0000:00:02.0: itc: No Data
<7> [108.589085] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [108.589086] i915 0000:00:02.0: quantization range: Full
<7> [108.589088] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [108.589089] i915 0000:00:02.0: video code: 0
<7> [108.589091] i915 0000:00:02.0: ycc quantization range: Full
<7> [108.589092] i915 0000:00:02.0: hdmi content type: Graphics
<7> [108.589093] i915 0000:00:02.0: pixel repeat: 0
<7> [108.589095] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [108.589097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [108.589255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.589410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.589567] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [108.589569] i915 0000:00:02.0: vendor: Intel
<7> [108.589570] i915 0000:00:02.0: product: Integrated gfx
<7> [108.589572] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [108.589574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [108.589732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [108.589906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [108.590061] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [108.590063] i915 0000:00:02.0: empty frame
<7> [108.590065] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [108.590264] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [108.590422] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [108.590620] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [108.590786] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [108.590939] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [108.591090] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [108.591241] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [108.591393] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [108.591565] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 1142000 num active planes 1
<7> [108.591735] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [108.591895] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [108.592073] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [108.592255] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [108.592451] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [108.592608] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [108.592829] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [108.593057] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [108.593297] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [108.593536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [108.593785] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [108.594019] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [108.594260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [108.594494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [108.594731] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [108.594955] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [108.595181] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [108.595414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [108.595649] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [108.595652] i915 0000:00:02.0: colorspace: RGB
<7> [108.595653] i915 0000:00:02.0: scan mode: Underscan
<7> [108.595655] i915 0000:00:02.0: colorimetry: No Data
<7> [108.595656] i915 0000:00:02.0: picture aspect: No Data
<7> [108.595657] i915 0000:00:02.0: active aspect: Same as Picture
<7> [108.595659] i915 0000:00:02.0: itc: No Data
<7> [108.595660] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [108.595662] i915 0000:00:02.0: quantization range: Full
<7> [108.595663] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [108.595664] i915 0000:00:02.0: video code: 0
<7> [108.595666] i915 0000:00:02.0: ycc quantization range: Full
<7> [108.595667] i915 0000:00:02.0: hdmi content type: Graphics
<7> [108.595669] i915 0000:00:02.0: pixel repeat: 0
<7> [108.595670] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [108.595672] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [108.595674] i915 0000:00:02.0: vendor: Intel
<7> [108.595685] i915 0000:00:02.0: product: Integrated gfx
<7> [108.595687] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [108.595689] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [108.595690] i915 0000:00:02.0: empty frame
<7> [108.595692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [108.595852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [108.596009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [108.596165] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [108.596321] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [108.596478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [108.596634] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [108.596812] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [108.596844] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [108.597038] 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> [108.597123] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [108.597251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [108.597416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [108.597378] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [108.597577] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [108.597756] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [108.597969] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [108.598207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [108.598371] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [108.598530] 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> [108.598638] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [108.598700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [108.598861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [108.599101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [108.599271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [108.599426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [108.599588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [108.599783] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [108.600009] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [108.600237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.600470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.600709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.600948] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [108.601191] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [108.601417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.601651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.601873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [108.602109] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [108.602344] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [108.602571] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [108.602759] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [108.602920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [108.603078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [108.603235] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [108.603391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [108.603547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [108.603720] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [108.603875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [108.604131] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [108.604298] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [108.604994] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [108.606182] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [108.606353] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [108.606526] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [108.606729] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [108.606926] i915 0000:00:02.0: [drm:intel_set_cdclk [i915]] Pre changing CDCLK to 192000 kHz, VCO 384000 kHz, ref 38400 kHz, bypass 19200 kHz, voltage level 0
<7> [108.607153] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [108.607381] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [108.607621] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [108.607849] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [108.608081] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [108.608309] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [108.608557] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [108.608778] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [108.608995] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [108.609228] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [108.609453] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [108.609691] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [108.609914] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [108.610136] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [108.610328] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [108.610485] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [108.610678] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [108.610838] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [108.611009] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [108.611178] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [108.611333] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [108.611628] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [108.611832] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [108.611997] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [108.612227] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [108.612470] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [108.613209] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [108.613857] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [108.614002] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [108.614649] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [108.614880] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [108.614906] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [108.631986] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:585:pipe D], 64 bytes ELD
<7> [108.632947] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [108.637764] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [108.637808] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [108.640267] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [108.640458] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [108.640626] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [108.640796] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [108.640799] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [108.640801] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [108.640802] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [108.640942] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [108.641272] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<6> [116.380020] [IGT] kms_flip: finished subtest D-HDMI-A1, FAIL
<6> [116.382011] [IGT] kms_flip: finished subtest wf_vblank-ts-check-interruptible, FAIL
<7> [116.385469] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:638] from all active usage due to RMFB ioctl
<7> [116.385743] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:449:plane 1D] because [FB:638] is removed
<7> [116.385796] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [116.386390] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [116.386572] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [116.386777] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [116.386957] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [116.387185] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [116.387368] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [116.387582] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [116.392742] [IGT] kms_flip: exiting, ret=98
<7> [116.407202] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [116.407415] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [116.407631] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [116.407924] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [116.408093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [116.408260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [116.408423] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [116.408589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [116.408753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [116.408980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [116.409185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [116.409346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [116.409504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [116.409663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [116.409821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [116.410043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [116.410251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [116.410411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [116.410570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [116.410726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [116.410892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [116.411050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [116.411206] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [116.411363] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [116.411519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [116.411676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [116.411833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [116.412007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [116.412203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [116.412361] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [116.412517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [116.412676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [116.412833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [116.413051] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [116.413220] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [116.413377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [116.413533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [116.413689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [116.413845] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [116.414094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [116.414326] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [116.414548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [116.414781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [116.414970] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [116.415132] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [116.415290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.415451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.415607] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [116.415610] i915 0000:00:02.0: colorspace: RGB
<7> [116.415612] i915 0000:00:02.0: scan mode: Underscan
<7> [116.415613] i915 0000:00:02.0: colorimetry: No Data
<7> [116.415615] i915 0000:00:02.0: picture aspect: No Data
<7> [116.415617] i915 0000:00:02.0: active aspect: Same as Picture
<7> [116.415618] i915 0000:00:02.0: itc: No Data
<7> [116.415620] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [116.415622] i915 0000:00:02.0: quantization range: Full
<7> [116.415623] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [116.415625] i915 0000:00:02.0: video code: 0
<7> [116.415626] i915 0000:00:02.0: ycc quantization range: Full
<7> [116.415628] i915 0000:00:02.0: hdmi content type: Graphics
<7> [116.415630] i915 0000:00:02.0: pixel repeat: 0
<7> [116.415632] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [116.415634] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [116.415791] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.415961] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.416117] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [116.416119] i915 0000:00:02.0: vendor: Intel
<7> [116.416121] i915 0000:00:02.0: product: Integrated gfx
<7> [116.416122] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [116.416124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [116.416281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.416436] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.416594] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [116.416597] i915 0000:00:02.0: empty frame
<7> [116.416599] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [116.416754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [116.416928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [116.417093] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [116.417250] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [116.417408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [116.417569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [116.417769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [116.417951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [116.418109] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [116.418265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [116.418464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [116.418624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [116.418782] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [116.418953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [116.419119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [116.419310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [116.419467] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [116.419624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [116.419780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [116.419993] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [116.420158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [116.420319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [116.420476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [116.420671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [116.420847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [116.421006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [116.421162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [116.421329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [116.421567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [116.421729] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [116.421901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [116.422058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [116.422217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [116.422416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [116.422574] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [116.422732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [116.422903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [116.423069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [116.423259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [116.423416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [116.423577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [116.423733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.423946] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [116.423948] i915 0000:00:02.0: colorspace: RGB
<7> [116.423950] i915 0000:00:02.0: scan mode: Underscan
<7> [116.423951] i915 0000:00:02.0: colorimetry: No Data
<7> [116.423953] i915 0000:00:02.0: picture aspect: No Data
<7> [116.423955] i915 0000:00:02.0: active aspect: Same as Picture
<7> [116.423957] i915 0000:00:02.0: itc: No Data
<7> [116.423958] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [116.423960] i915 0000:00:02.0: quantization range: Full
<7> [116.423962] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [116.423963] i915 0000:00:02.0: video code: 0
<7> [116.423965] i915 0000:00:02.0: ycc quantization range: Full
<7> [116.423966] i915 0000:00:02.0: hdmi content type: Graphics
<7> [116.423968] i915 0000:00:02.0: pixel repeat: 0
<7> [116.423970] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [116.423972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.424137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [116.424294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.424450] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [116.424452] i915 0000:00:02.0: vendor: Intel
<7> [116.424454] i915 0000:00:02.0: product: Integrated gfx
<7> [116.424456] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [116.424458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.424655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [116.424820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [116.425010] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [116.425013] i915 0000:00:02.0: empty frame
<7> [116.425014] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [116.425171] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [116.425369] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [116.425587] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [116.425747] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [116.425942] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [116.426134] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [116.426293] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [116.426446] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [116.426601] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [116.426754] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [116.426926] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [116.427127] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [116.427290] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [116.427452] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [116.427629] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [116.427793] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [116.427999] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [116.428188] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [116.428357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [116.428519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [116.428681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [116.428857] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [116.429043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [116.429204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [116.429363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [116.429522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [116.429679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [116.429870] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [116.429873] i915 0000:00:02.0: colorspace: RGB
<7> [116.429876] i915 0000:00:02.0: scan mode: Underscan
<7> [116.429879] i915 0000:00:02.0: colorimetry: No Data
<7> [116.429882] i915 0000:00:02.0: picture aspect: No Data
<7> [116.429885] i915 0000:00:02.0: active aspect: Same as Picture
<7> [116.429889] i915 0000:00:02.0: itc: No Data
<7> [116.429891] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [116.429894] i915 0000:00:02.0: quantization range: Full
<7> [116.429896] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [116.429898] i915 0000:00:02.0: video code: 0
<7> [116.429901] i915 0000:00:02.0: ycc quantization range: Full
<7> [116.429904] i915 0000:00:02.0: hdmi content type: Graphics
<7> [116.429906] i915 0000:00:02.0: pixel repeat: 0
<7> [116.429909] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [116.429912] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [116.429915] i915 0000:00:02.0: vendor: Intel
<7> [116.429918] i915 0000:00:02.0: product: Integrated gfx
<7> [116.429920] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [116.429923] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [116.429926] i915 0000:00:02.0: empty frame
<7> [116.429929] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 10 00 0f 00 6d a2 00 7f 00 00 00 00 00 00 00 00
<7> [116.430092] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 0d 82 34 12 66 69 74 48 65 61 64 6c 65 73 73 47
<7> [116.430247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 53 7f 7f 28 0f 7f 07 17 7f ff 3f 7f ff 47 7f ff
<7> [116.430402] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ELD: 5f 7f 82 7f 7f 00 7f 7f 51 7f 7f 30 77 7f 03 00
<7> [116.430557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [116.430713] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [116.430887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1144, vmax: 1144, flipline: 1144, pipeline full: 61, guardband: 63 vsync start: 0, vsync end: 0
<7> [116.431043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1144, vmax vtotal: 1144
<7> [116.431198] 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> [116.431354] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [116.431508] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [116.431664] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [116.431830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x40 0x5
<7> [116.431985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=285500, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1080 vb=1080-1144 vs=1083-1088 vt=1144, flags=0x5
<7> [116.432140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [116.432294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [116.432450] 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> [116.432604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [116.432758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [116.432932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [116.433087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [116.433241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1801be, cfgcr1: 0x488, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [116.433397] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [116.433558] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [116.433717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [116.433913] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.434106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.434262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.434418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [116.434574] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [116.434729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.434903] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.435082] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [116.435238] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [116.435392] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [116.435548] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [116.435704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [116.435875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [116.436061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [116.436215] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [116.436371] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [116.436527] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [116.436680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [116.436855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [116.437032] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [116.437186] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [116.437340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [116.437495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [116.437651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [116.437823] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [116.438002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [116.438157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [116.438312] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [116.438621] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:585:pipe D]
<7> [116.449808] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [116.451720] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [116.452264] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [116.452412] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [116.453007] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [116.453196] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [116.453217] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [116.453473] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [116.458709] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [116.458910] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [116.459103] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [116.459279] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [116.459444] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [116.459604] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [116.459780] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [116.459966] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [116.460125] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [116.460282] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [116.460438] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [116.460594] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [116.460766] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [116.460943] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [116.461099] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [116.461254] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [116.461409] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [116.461564] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [116.461733] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [116.461917] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [116.462073] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [116.462227] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [116.462380] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [116.462534] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [116.462936] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [116.463102] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [116.463297] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [116.463545] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [116.464308] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [116.465320] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [116.465480] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [116.466147] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [116.466309] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [116.466327] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [116.474975] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [116.475161] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [116.483293] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:171:pipe A], 64 bytes ELD
<7> [116.491635] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] HDMI audio pixel clock setting for 285500 not found, falling back to defaults
<7> [116.491824] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [116.491986] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [116.492150] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [116.492153] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [116.492155] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [116.492157] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [116.492311] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [116.492607] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [116.493382] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [116.493646] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [116.494056] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [116.494474] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<7> [116.495666] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [116.500093] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [116.500127] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
Created at 2026-05-11 19:24:08