Results for igt@kms_flip@flip-vs-blocking-wf-vblank@a-hdmi-a1

Result: Fail

git-log-oneline i915_display_info4 igt_runner4 runtimes4 results4.json results4-i915-load.json guc_logs4.tar i915_display_info_post_exec4 boot4 dmesg4

DetailValue
Duration 19.11 seconds
Hostname
shard-tglu-3
Igt-Version
IGT-Version: 2.3-g889245228 (x86_64) (Linux: 7.0.0-rc1-CI_DRM_18056-g340c78371713+ x86_64)
Out
Using IGT_SRANDOM=1772155740 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-blocking-wf-vblank
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8334.9us +- 2.705us accuracy 0.10%
Event flip: expected 906, counted 920, passrate = 57.61%, encoder type 2
Event vblank: expected 906, counted 920, passrate = 56.52%, encoder type 2
Expected frametime: 8335us; measured 8188.9us +- 3.775us accuracy 0.14%
vblank interval differs from modeline! expected 8334.6us, measured 8189us +- 3.775us, difference 145.7us (38.6 sigma)
Event flip: expected 915, counted 910, passrate = 35.16%, encoder type 2
Event vblank: expected 915, counted 910, passrate = 34.07%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (19.106s)
Err
Starting subtest: flip-vs-blocking-wf-vblank
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2059) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2059) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.229321
last_received_ts = 59.303406
last_seq = 2694
current_ts = 59.311813
current_received_ts = 59.385979
current_seq = 2704
count = 51
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.229321/2694, current 59.311813/2704: elapsed=82491.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.229321/2694, current 59.311813/2704: elapsed=82491.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.303566
last_received_ts = 59.303352
last_seq = 2703
current_ts = 59.386055
current_received_ts = 59.385929
current_seq = 2713
count = 51
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.303566/2703, current 59.386055/2713: elapsed=82490.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.303566/2703, current 59.386055/2713: elapsed=82490.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.311813
last_received_ts = 59.385979
last_seq = 2704
current_ts = 59.394302
current_received_ts = 59.468479
current_seq = 2714
count = 52
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.311813/2704, current 59.394302/2714: elapsed=82489.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.311813/2704, current 59.394302/2714: elapsed=82489.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.386055
last_received_ts = 59.385929
last_seq = 2713
current_ts = 59.468540
current_received_ts = 59.468430
current_seq = 2723
count = 52
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.386055/2713, current 59.468540/2723: elapsed=82486.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.386055/2713, current 59.468540/2723: elapsed=82486.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.394302
last_received_ts = 59.468479
last_seq = 2714
current_ts = 59.476795
current_received_ts = 59.550964
current_seq = 2724
count = 53
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.394302/2714, current 59.476795/2724: elapsed=82493.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.394302/2714, current 59.476795/2724: elapsed=82493.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.468540
last_received_ts = 59.468430
last_seq = 2723
current_ts = 59.551037
current_received_ts = 59.550915
current_seq = 2733
count = 53
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.468540/2723, current 59.551037/2733: elapsed=82494.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.468540/2723, current 59.551037/2733: elapsed=82494.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.476795
last_received_ts = 59.550964
last_seq = 2724
current_ts = 59.559280
current_received_ts = 59.633469
current_seq = 2734
count = 54
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.476795/2724, current 59.559280/2734: elapsed=82487.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.476795/2724, current 59.559280/2734: elapsed=82487.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.551037
last_received_ts = 59.550915
last_seq = 2733
current_ts = 59.633533
current_received_ts = 59.633415
current_seq = 2743
count = 54
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.551037/2733, current 59.633533/2743: elapsed=82500.0us expected=81888.8us +- 409.4us, error 0.7%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.551037/2733, current 59.633533/2743: elapsed=82500.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.559280
last_received_ts = 59.633469
last_seq = 2734
current_ts = 59.641800
current_received_ts = 59.716049
current_seq = 2744
count = 55
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.559280/2734, current 59.641800/2744: elapsed=82518.0us expected=81888.8us +- 409.4us, error 0.8%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.559280/2734, current 59.641800/2744: elapsed=82518.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.633533
last_received_ts = 59.633415
last_seq = 2743
current_ts = 59.716133
current_received_ts = 59.715961
current_seq = 2753
count = 55
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.633533/2743, current 59.716133/2753: elapsed=82600.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.633533/2743, current 59.716133/2753: elapsed=82600.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.641800
last_received_ts = 59.716049
last_seq = 2744
current_ts = 59.724396
current_received_ts = 59.798550
current_seq = 2754
count = 56
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.641800/2744, current 59.724396/2754: elapsed=82597.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.641800/2744, current 59.724396/2754: elapsed=82597.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.716133
last_received_ts = 59.715961
last_seq = 2753
current_ts = 59.798737
current_received_ts = 59.798496
current_seq = 2763
count = 56
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.716133/2753, current 59.798737/2763: elapsed=82600.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.716133/2753, current 59.798737/2763: elapsed=82600.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.724396
last_received_ts = 59.798550
last_seq = 2754
current_ts = 59.806992
current_received_ts = 59.881287
current_seq = 2764
count = 57
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.724396/2754, current 59.806992/2764: elapsed=82594.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.724396/2754, current 59.806992/2764: elapsed=82594.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.798737
last_received_ts = 59.798496
last_seq = 2763
current_ts = 59.881332
current_received_ts = 59.881233
current_seq = 2773
count = 57
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.798737/2763, current 59.881332/2773: elapsed=82596.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.798737/2763, current 59.881332/2773: elapsed=82596.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.806992
last_received_ts = 59.881287
last_seq = 2764
current_ts = 59.889591
current_received_ts = 59.963768
current_seq = 2774
count = 58
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.806992/2764, current 59.889591/2774: elapsed=82600.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.806992/2764, current 59.889591/2774: elapsed=82600.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.881332
last_received_ts = 59.881233
last_seq = 2773
current_ts = 59.963928
current_received_ts = 59.963718
current_seq = 2783
count = 58
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.881332/2773, current 59.963928/2783: elapsed=82598.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.881332/2773, current 59.963928/2783: elapsed=82598.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.889591
last_received_ts = 59.963768
last_seq = 2774
current_ts = 59.972195
current_received_ts = 60.046459
current_seq = 2784
count = 59
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.889591/2774, current 59.972195/2784: elapsed=82602.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.889591/2774, current 59.972195/2784: elapsed=82602.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 59.963928
last_received_ts = 59.963718
last_seq = 2783
current_ts = 60.046528
current_received_ts = 60.046410
current_seq = 2793
count = 59
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 59.963928/2783, current 60.046528/2793: elapsed=82597.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 59.963928/2783, current 60.046528/2793: elapsed=82597.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 59.972195
last_received_ts = 60.046459
last_seq = 2784
current_ts = 60.054790
current_received_ts = 60.128956
current_seq = 2794
count = 60
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 59.972195/2784, current 60.054790/2794: elapsed=82597.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 59.972195/2784, current 60.054790/2794: elapsed=82597.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.046528
last_received_ts = 60.046410
last_seq = 2793
current_ts = 60.129124
current_received_ts = 60.128902
current_seq = 2803
count = 60
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.046528/2793, current 60.129124/2803: elapsed=82599.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.046528/2793, current 60.129124/2803: elapsed=82599.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.054790
last_received_ts = 60.128956
last_seq = 2794
current_ts = 60.137390
current_received_ts = 60.211639
current_seq = 2804
count = 61
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.054790/2794, current 60.137390/2804: elapsed=82600.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.054790/2794, current 60.137390/2804: elapsed=82600.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.129124
last_received_ts = 60.128902
last_seq = 2803
current_ts = 60.211723
current_received_ts = 60.211590
current_seq = 2813
count = 61
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.129124/2803, current 60.211723/2813: elapsed=82599.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.129124/2803, current 60.211723/2813: elapsed=82599.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.137390
last_received_ts = 60.211639
last_seq = 2804
current_ts = 60.219986
current_received_ts = 60.294201
current_seq = 2814
count = 62
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.137390/2804, current 60.219986/2814: elapsed=82596.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.137390/2804, current 60.219986/2814: elapsed=82596.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.211723
last_received_ts = 60.211590
last_seq = 2813
current_ts = 60.294319
current_received_ts = 60.294147
current_seq = 2823
count = 62
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.211723/2813, current 60.294319/2823: elapsed=82595.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.211723/2813, current 60.294319/2823: elapsed=82595.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.219986
last_received_ts = 60.294201
last_seq = 2814
current_ts = 60.302582
current_received_ts = 60.376881
current_seq = 2824
count = 63
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.219986/2814, current 60.302582/2824: elapsed=82596.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.219986/2814, current 60.302582/2824: elapsed=82596.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.294319
last_received_ts = 60.294147
last_seq = 2823
current_ts = 60.376919
current_received_ts = 60.376827
current_seq = 2833
count = 63
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.294319/2823, current 60.376919/2833: elapsed=82599.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.294319/2823, current 60.376919/2833: elapsed=82599.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.302582
last_received_ts = 60.376881
last_seq = 2824
current_ts = 60.385178
current_received_ts = 60.459473
current_seq = 2834
count = 64
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.302582/2824, current 60.385178/2834: elapsed=82597.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.302582/2824, current 60.385178/2834: elapsed=82597.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.376919
last_received_ts = 60.376827
last_seq = 2833
current_ts = 60.459518
current_received_ts = 60.459419
current_seq = 2843
count = 64
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.376919/2833, current 60.459518/2843: elapsed=82599.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.376919/2833, current 60.459518/2843: elapsed=82599.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.385178
last_received_ts = 60.459473
last_seq = 2834
current_ts = 60.467777
current_received_ts = 60.541901
current_seq = 2844
count = 65
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.385178/2834, current 60.467777/2844: elapsed=82600.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.385178/2834, current 60.467777/2844: elapsed=82600.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.459518
last_received_ts = 60.459419
last_seq = 2843
current_ts = 60.542118
current_received_ts = 60.541851
current_seq = 2853
count = 65
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.459518/2843, current 60.542118/2853: elapsed=82602.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.459518/2843, current 60.542118/2853: elapsed=82602.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.467777
last_received_ts = 60.541901
last_seq = 2844
current_ts = 60.550377
current_received_ts = 60.624657
current_seq = 2854
count = 66
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.467777/2844, current 60.550377/2854: elapsed=82599.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.467777/2844, current 60.550377/2854: elapsed=82599.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.542118
last_received_ts = 60.541851
last_seq = 2853
current_ts = 60.624714
current_received_ts = 60.624607
current_seq = 2863
count = 66
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.542118/2853, current 60.624714/2863: elapsed=82595.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.542118/2853, current 60.624714/2863: elapsed=82595.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.550377
last_received_ts = 60.624657
last_seq = 2854
current_ts = 60.632988
current_received_ts = 60.707359
current_seq = 2864
count = 67
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.550377/2854, current 60.632988/2864: elapsed=82609.0us expected=81888.8us +- 409.4us, error 0.9%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.550377/2854, current 60.632988/2864: elapsed=82609.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.624714
last_received_ts = 60.624607
last_seq = 2863
current_ts = 60.707409
current_received_ts = 60.707310
current_seq = 2873
count = 67
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.624714/2863, current 60.707409/2873: elapsed=82694.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.624714/2863, current 60.707409/2873: elapsed=82694.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.632988
last_received_ts = 60.707359
last_seq = 2864
current_ts = 60.715679
current_received_ts = 60.790066
current_seq = 2874
count = 68
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.632988/2864, current 60.715679/2874: elapsed=82693.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.632988/2864, current 60.715679/2874: elapsed=82693.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.707409
last_received_ts = 60.707310
last_seq = 2873
current_ts = 60.790096
current_received_ts = 60.789970
current_seq = 2883
count = 68
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.707409/2873, current 60.790096/2883: elapsed=82687.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.707409/2873, current 60.790096/2883: elapsed=82687.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.715679
last_received_ts = 60.790066
last_seq = 2874
current_ts = 60.798367
current_received_ts = 60.872669
current_seq = 2884
count = 69
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.715679/2874, current 60.798367/2884: elapsed=82688.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.715679/2874, current 60.798367/2884: elapsed=82688.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.790096
last_received_ts = 60.789970
last_seq = 2883
current_ts = 60.872787
current_received_ts = 60.872620
current_seq = 2893
count = 69
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.790096/2883, current 60.872787/2893: elapsed=82693.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.790096/2883, current 60.872787/2893: elapsed=82693.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.798367
last_received_ts = 60.872669
last_seq = 2884
current_ts = 60.881062
current_received_ts = 60.955414
current_seq = 2894
count = 70
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.798367/2884, current 60.881062/2894: elapsed=82694.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.798367/2884, current 60.881062/2894: elapsed=82694.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.872787
last_received_ts = 60.872620
last_seq = 2893
current_ts = 60.955482
current_received_ts = 60.955360
current_seq = 2903
count = 70
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.872787/2893, current 60.955482/2903: elapsed=82694.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.872787/2893, current 60.955482/2903: elapsed=82694.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.881062
last_received_ts = 60.955414
last_seq = 2894
current_ts = 60.963753
current_received_ts = 61.038128
current_seq = 2904
count = 71
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.881062/2894, current 60.963753/2904: elapsed=82692.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.881062/2894, current 60.963753/2904: elapsed=82692.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 60.955482
last_received_ts = 60.955360
last_seq = 2903
current_ts = 61.038174
current_received_ts = 61.038078
current_seq = 2913
count = 71
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 60.955482/2903, current 61.038174/2913: elapsed=82690.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 60.955482/2903, current 61.038174/2913: elapsed=82690.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 60.963753
last_received_ts = 61.038128
last_seq = 2904
current_ts = 61.046448
current_received_ts = 61.120811
current_seq = 2914
count = 72
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 60.963753/2904, current 61.046448/2914: elapsed=82692.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 60.963753/2904, current 61.046448/2914: elapsed=82692.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.038174
last_received_ts = 61.038078
last_seq = 2913
current_ts = 61.120861
current_received_ts = 61.120762
current_seq = 2923
count = 72
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.038174/2913, current 61.120861/2923: elapsed=82688.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.038174/2913, current 61.120861/2923: elapsed=82688.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.046448
last_received_ts = 61.120811
last_seq = 2914
current_ts = 61.129135
current_received_ts = 61.203518
current_seq = 2924
count = 73
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.046448/2914, current 61.129135/2924: elapsed=82688.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.046448/2914, current 61.129135/2924: elapsed=82688.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.120861
last_received_ts = 61.120762
last_seq = 2923
current_ts = 61.203552
current_received_ts = 61.203465
current_seq = 2933
count = 73
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.120861/2923, current 61.203552/2933: elapsed=82693.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.120861/2923, current 61.203552/2933: elapsed=82693.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.129135
last_received_ts = 61.203518
last_seq = 2924
current_ts = 61.211826
current_received_ts = 61.286171
current_seq = 2934
count = 74
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.129135/2924, current 61.211826/2934: elapsed=82693.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.129135/2924, current 61.211826/2934: elapsed=82693.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.203552
last_received_ts = 61.203465
last_seq = 2933
current_ts = 61.286243
current_received_ts = 61.286118
current_seq = 2943
count = 74
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.203552/2933, current 61.286243/2943: elapsed=82691.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.203552/2933, current 61.286243/2943: elapsed=82691.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.211826
last_received_ts = 61.286171
last_seq = 2934
current_ts = 61.294514
current_received_ts = 61.368774
current_seq = 2944
count = 75
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.211826/2934, current 61.294514/2944: elapsed=82687.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.211826/2934, current 61.294514/2944: elapsed=82687.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.286243
last_received_ts = 61.286118
last_seq = 2943
current_ts = 61.368938
current_received_ts = 61.368725
current_seq = 2953
count = 75
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.286243/2943, current 61.368938/2953: elapsed=82695.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.286243/2943, current 61.368938/2953: elapsed=82695.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.294514
last_received_ts = 61.368774
last_seq = 2944
current_ts = 61.377213
current_received_ts = 61.451557
current_seq = 2954
count = 76
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.294514/2944, current 61.377213/2954: elapsed=82698.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.294514/2944, current 61.377213/2954: elapsed=82698.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.368938
last_received_ts = 61.368725
last_seq = 2953
current_ts = 61.451633
current_received_ts = 61.451508
current_seq = 2963
count = 76
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.368938/2953, current 61.451633/2963: elapsed=82694.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.368938/2953, current 61.451633/2963: elapsed=82694.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.377213
last_received_ts = 61.451557
last_seq = 2954
current_ts = 61.459900
current_received_ts = 61.534275
current_seq = 2964
count = 77
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.377213/2954, current 61.459900/2964: elapsed=82687.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.377213/2954, current 61.459900/2964: elapsed=82687.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.451633
last_received_ts = 61.451508
last_seq = 2963
current_ts = 61.534321
current_received_ts = 61.534222
current_seq = 2973
count = 77
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.451633/2963, current 61.534321/2973: elapsed=82688.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.451633/2963, current 61.534321/2973: elapsed=82688.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.459900
last_received_ts = 61.534275
last_seq = 2964
current_ts = 61.542595
current_received_ts = 61.616669
current_seq = 2974
count = 78
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.459900/2964, current 61.542595/2974: elapsed=82695.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.459900/2964, current 61.542595/2974: elapsed=82695.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.534321
last_received_ts = 61.534222
last_seq = 2973
current_ts = 61.617012
current_received_ts = 61.616646
current_seq = 2983
count = 78
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.534321/2973, current 61.617012/2983: elapsed=82690.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.534321/2973, current 61.617012/2983: elapsed=82690.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.542595
last_received_ts = 61.616669
last_seq = 2974
current_ts = 61.625282
current_received_ts = 61.699692
current_seq = 2984
count = 79
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.542595/2974, current 61.625282/2984: elapsed=82689.0us expected=81888.8us +- 409.4us, error 1.0%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.542595/2974, current 61.625282/2984: elapsed=82689.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.617012
last_received_ts = 61.616646
last_seq = 2983
current_ts = 61.699780
current_received_ts = 61.699650
current_seq = 2993
count = 79
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.617012/2983, current 61.699780/2993: elapsed=82767.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.617012/2983, current 61.699780/2993: elapsed=82767.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.625282
last_received_ts = 61.699692
last_seq = 2984
current_ts = 61.708061
current_received_ts = 61.782471
current_seq = 2994
count = 80
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.625282/2984, current 61.708061/2994: elapsed=82778.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.625282/2984, current 61.708061/2994: elapsed=82778.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.699780
last_received_ts = 61.699650
last_seq = 2993
current_ts = 61.782555
current_received_ts = 61.782421
current_seq = 3003
count = 80
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.699780/2993, current 61.782555/3003: elapsed=82776.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.699780/2993, current 61.782555/3003: elapsed=82776.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.708061
last_received_ts = 61.782471
last_seq = 2994
current_ts = 61.790833
current_received_ts = 61.865246
current_seq = 3004
count = 81
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.708061/2994, current 61.790833/3004: elapsed=82773.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.708061/2994, current 61.790833/3004: elapsed=82773.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.782555
last_received_ts = 61.782421
last_seq = 3003
current_ts = 61.865326
current_received_ts = 61.865196
current_seq = 3013
count = 81
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.782555/3003, current 61.865326/3013: elapsed=82770.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.782555/3003, current 61.865326/3013: elapsed=82770.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.790833
last_received_ts = 61.865246
last_seq = 3004
current_ts = 61.873608
current_received_ts = 61.948048
current_seq = 3014
count = 82
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.790833/3004, current 61.873608/3014: elapsed=82773.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.790833/3004, current 61.873608/3014: elapsed=82773.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.865326
last_received_ts = 61.865196
last_seq = 3013
current_ts = 61.948105
current_received_ts = 61.947968
current_seq = 3023
count = 82
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.865326/3013, current 61.948105/3023: elapsed=82779.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.865326/3013, current 61.948105/3023: elapsed=82779.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.873608
last_received_ts = 61.948048
last_seq = 3014
current_ts = 61.956383
current_received_ts = 62.030792
current_seq = 3024
count = 83
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.873608/3014, current 61.956383/3024: elapsed=82775.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.873608/3014, current 61.956383/3024: elapsed=82775.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 61.948105
last_received_ts = 61.947968
last_seq = 3023
current_ts = 62.030872
current_received_ts = 62.030739
current_seq = 3033
count = 83
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 61.948105/3023, current 62.030872/3033: elapsed=82771.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 61.948105/3023, current 62.030872/3033: elapsed=82771.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 61.956383
last_received_ts = 62.030792
last_seq = 3024
current_ts = 62.039150
current_received_ts = 62.113445
current_seq = 3034
count = 84
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 61.956383/3024, current 62.039150/3034: elapsed=82770.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 61.956383/3024, current 62.039150/3034: elapsed=82770.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.030872
last_received_ts = 62.030739
last_seq = 3033
current_ts = 62.113644
current_received_ts = 62.113396
current_seq = 3043
count = 84
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.030872/3033, current 62.113644/3043: elapsed=82771.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.030872/3033, current 62.113644/3043: elapsed=82771.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.039150
last_received_ts = 62.113445
last_seq = 3034
current_ts = 62.121922
current_received_ts = 62.196339
current_seq = 3044
count = 85
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.039150/3034, current 62.121922/3044: elapsed=82771.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.039150/3034, current 62.121922/3044: elapsed=82771.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.113644
last_received_ts = 62.113396
last_seq = 3043
current_ts = 62.196423
current_received_ts = 62.196285
current_seq = 3053
count = 85
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.113644/3043, current 62.196423/3053: elapsed=82776.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.113644/3043, current 62.196423/3053: elapsed=82776.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.121922
last_received_ts = 62.196339
last_seq = 3044
current_ts = 62.204700
current_received_ts = 62.279121
current_seq = 3054
count = 86
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.121922/3044, current 62.204700/3054: elapsed=82779.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.121922/3044, current 62.204700/3054: elapsed=82779.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.196423
last_received_ts = 62.196285
last_seq = 3053
current_ts = 62.279194
current_received_ts = 62.279072
current_seq = 3063
count = 86
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.196423/3053, current 62.279194/3063: elapsed=82773.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.196423/3053, current 62.279194/3063: elapsed=82773.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.204700
last_received_ts = 62.279121
last_seq = 3054
current_ts = 62.287472
current_received_ts = 62.361885
current_seq = 3064
count = 87
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.204700/3054, current 62.287472/3064: elapsed=82771.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.204700/3054, current 62.287472/3064: elapsed=82771.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.279194
last_received_ts = 62.279072
last_seq = 3063
current_ts = 62.361965
current_received_ts = 62.361832
current_seq = 3073
count = 87
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.279194/3063, current 62.361965/3073: elapsed=82773.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.279194/3063, current 62.361965/3073: elapsed=82773.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.287472
last_received_ts = 62.361885
last_seq = 3064
current_ts = 62.370247
current_received_ts = 62.444660
current_seq = 3074
count = 88
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.287472/3064, current 62.370247/3074: elapsed=82774.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.287472/3064, current 62.370247/3074: elapsed=82774.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.361965
last_received_ts = 62.361832
last_seq = 3073
current_ts = 62.444736
current_received_ts = 62.444607
current_seq = 3083
count = 88
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.361965/3073, current 62.444736/3083: elapsed=82771.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.361965/3073, current 62.444736/3083: elapsed=82771.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.370247
last_received_ts = 62.444660
last_seq = 3074
current_ts = 62.453014
current_received_ts = 62.527435
current_seq = 3084
count = 89
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.370247/3074, current 62.453014/3084: elapsed=82769.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.370247/3074, current 62.453014/3084: elapsed=82769.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.444736
last_received_ts = 62.444607
last_seq = 3083
current_ts = 62.527512
current_received_ts = 62.527386
current_seq = 3093
count = 89
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.444736/3083, current 62.527512/3093: elapsed=82774.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.444736/3083, current 62.527512/3093: elapsed=82774.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = flip
last_ts = 62.453014
last_received_ts = 62.527435
last_seq = 3084
current_ts = 62.535789
current_received_ts = 62.610214
current_seq = 3094
count = 90
seq_step = 1
(kms_flip:2059) DEBUG: flip ts/seq: last 62.453014/3084, current 62.535789/3094: elapsed=82774.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent flip ts/seq: last 62.453014/3084, current 62.535789/3094: elapsed=82774.0us expected=81888.8us
(kms_flip:2059) DEBUG: name = vblank
last_ts = 62.527512
last_received_ts = 62.527386
last_seq = 3093
current_ts = 62.610291
current_received_ts = 62.610165
current_seq = 3103
count = 90
seq_step = 10
(kms_flip:2059) DEBUG: vblank ts/seq: last 62.527512/3093, current 62.610291/3103: elapsed=82778.0us expected=81888.8us +- 409.4us, error 1.1%
(kms_flip:2059) DEBUG: inconsistent vblank ts/seq: last 62.527512/3093, current 62.610291/3103: elapsed=82778.0us expected=81888.8us
(kms_flip:2059) INFO: Event flip: expected 915, counted 910, passrate = 35.16%, encoder type 2
(kms_flip:2059) DEBUG: dropped frames, expected 915, counted 910, passrate = 35.16%, encoder type 2
(kms_flip:2059) INFO: Event vblank: expected 915, counted 910, passrate = 34.07%, encoder type 2
(kms_flip:2059) DEBUG: dropped frames, expected 915, counted 910, passrate = 34.07%, encoder type 2
(kms_flip:2059) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:2059) CRITICAL: Failed assertion: !retried
(kms_flip:2059) igt_core-INFO: Stack trace:
(kms_flip:2059) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2059) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2059) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:2059) igt_core-INFO:   #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
(kms_flip:2059) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:2059) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2059) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2059) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (19.106s)
Dmesg
<6> [43.917156] Console: switching to colour dummy device 80x25
<6> [43.917223] [IGT] kms_flip: executing
<7> [43.919252] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [43.920045] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [43.920332] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [43.930323] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [43.930333] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [43.934782] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [43.935274] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [43.935280] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [43.935619] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [43.936116] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [43.936121] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [43.936466] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [43.936951] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [43.936955] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [43.937322] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [43.938000] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [43.938022] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [43.965262] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [43.965272] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [43.965275] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] No deep color support on this HDMI sink.
<7> [43.965278] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [43.965281] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [43.965284] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [43.965286] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [43.965289] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [43.965760] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [43.965906] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [43.966522] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [43.966753] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [43.967668] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [43.967674] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [43.967678] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967683] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967686] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967690] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967694] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967698] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967702] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967705] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [43.967709] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [43.967713] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [43.967716] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [43.967720] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3440x1440": 90 476140 3440 3448 3480 3520 1440 1441 1449 1503 0x40 0xa
<7> [43.967724] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 75 443250 2560 2768 3048 3536 1600 1603 1609 1672 0x40 0x6
<7> [43.967727] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 348500 2560 2752 3032 3504 1600 1603 1609 1658 0x40 0x6
<7> [43.967731] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 120 497770 2560 2608 2640 2720 1440 1443 1448 1525 0x40 0xa
<7> [43.967735] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 60 267027 2048 2200 2424 2800 1536 1537 1540 1589 0x0 0x6
<7> [43.967738] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 495000 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [43.967742] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 494505 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [43.967746] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 75 297000 1920 2064 2288 2640 1440 1441 1444 1500 0x40 0x6
<7> [43.967750] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 60 234000 1920 2048 2256 2600 1440 1441 1444 1500 0x40 0x6
<7> [43.967753] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2160x1200": 90 261420 2160 2208 2240 2320 1200 1203 1208 1252 0x40 0xa
<7> [43.967757] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 75 288000 1856 1984 2208 2560 1392 1393 1396 1500 0x40 0x6
<7> [43.967761] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 60 218250 1856 1952 2176 2528 1392 1393 1396 1439 0x40 0x6
<7> [43.967764] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 75 261000 1792 1888 2104 2456 1344 1345 1348 1417 0x40 0x6
<7> [43.967768] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 60 204750 1792 1920 2120 2448 1344 1345 1348 1394 0x40 0x6
<7> [43.967772] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1152": 60 162000 2048 2074 2154 2250 1152 1153 1156 1200 0x40 0x5
<7> [43.967776] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 120 317250 1920 1968 2000 2080 1200 1203 1209 1271 0x40 0xa
<7> [43.967779] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 75 245250 1920 2056 2264 2608 1200 1203 1209 1255 0x40 0x6
<7> [43.967783] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 193250 1920 2056 2256 2592 1200 1203 1209 1245 0x40 0x6
<7> [43.967787] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [43.967790] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [43.967794] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [43.967798] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [43.967801] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [43.967805] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 75 202500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [43.967809] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 70 189000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [43.967812] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 65 175500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [43.967816] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [43.967820] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 75 187000 1680 1800 1976 2272 1050 1053 1059 1099 0x40 0x6
<7> [43.967823] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [43.967827] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x945": 60 131481 1680 1784 1960 2240 945 946 949 978 0x0 0x6
<7> [43.967831] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 75 156000 1400 1504 1648 1896 1050 1053 1057 1099 0x40 0x6
<7> [43.967835] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 60 121750 1400 1488 1632 1864 1050 1053 1057 1089 0x40 0x6
<7> [43.967838] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [43.967842] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [43.967846] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [43.967849] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 75 136750 1440 1536 1688 1936 900 903 909 942 0x40 0x6
<7> [43.967853] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 106500 1440 1520 1672 1904 900 903 909 934 0x40 0x6
<7> [43.967857] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x960": 60 108000 1280 1376 1488 1800 960 961 964 1000 0x40 0x5
<7> [43.967860] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [43.967864] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 85500 1360 1424 1536 1792 768 771 777 795 0x40 0x5
<7> [43.967868] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 75 106500 1280 1360 1488 1696 800 803 809 838 0x40 0x6
<7> [43.967871] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [43.967875] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [43.967879] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 75 102250 1280 1360 1488 1696 768 771 778 805 0x40 0x6
<7> [43.967882] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 60 79500 1280 1344 1472 1664 768 771 778 798 0x40 0x6
<7> [43.967886] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [43.967890] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [43.967893] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [43.967897] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [43.967901] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46970 1024 1064 1168 1312 576 577 580 597 0x0 0x6
<7> [43.967904] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [43.967908] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [43.967912] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [43.967915] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [43.967919] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [43.967923] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [43.967926] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [43.967930] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [43.967934] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [43.967938] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [43.967941] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [43.967985] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [43.967990] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [43.972529] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [43.972548] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [43.972553] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [43.972873] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [43.972886] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [43.972890] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [43.973298] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [43.973322] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [43.973328] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [43.973695] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [43.974345] [IGT] kms_flip: starting subtest flip-vs-blocking-wf-vblank
<6> [43.977099] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [43.977532] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:636]
<7> [43.977627] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [44.043601] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [44.044171] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [44.044538] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [44.044797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [44.045072] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [44.045425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [44.045755] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [44.046040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [44.046324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [44.046686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [44.046947] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [44.047237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [44.047603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [44.047876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [44.048159] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [44.048411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [44.048701] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [44.048915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [44.049142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [44.049343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [44.049618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [44.049840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [44.050055] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [44.050254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [44.050540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [44.050751] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [44.050949] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [44.051174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [44.051397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [44.051553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [44.051708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [44.051865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [44.052062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [44.052292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [44.052464] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [44.052621] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [44.052778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [44.052933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [44.053175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [44.053348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [44.053503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [44.053659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [44.053823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.054067] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.054071] i915 0000:00:02.0: colorspace: RGB
<7> [44.054073] i915 0000:00:02.0: scan mode: Underscan
<7> [44.054075] i915 0000:00:02.0: colorimetry: No Data
<7> [44.054076] i915 0000:00:02.0: picture aspect: No Data
<7> [44.054078] i915 0000:00:02.0: active aspect: Same as Picture
<7> [44.054079] i915 0000:00:02.0: itc: No Data
<7> [44.054080] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [44.054082] i915 0000:00:02.0: quantization range: Full
<7> [44.054083] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [44.054085] i915 0000:00:02.0: video code: 0
<7> [44.054086] i915 0000:00:02.0: ycc quantization range: Full
<7> [44.054087] i915 0000:00:02.0: hdmi content type: Graphics
<7> [44.054089] i915 0000:00:02.0: pixel repeat: 0
<7> [44.054090] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [44.054092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.054252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [44.054411] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.054567] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.054569] i915 0000:00:02.0: vendor: Intel
<7> [44.054570] i915 0000:00:02.0: product: Integrated gfx
<7> [44.054572] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [44.054573] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.054771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [44.055016] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.055243] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.055247] i915 0000:00:02.0: empty frame
<7> [44.055249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.055428] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [44.055588] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [44.055781] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [44.055948] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [44.056255] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [44.056415] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [44.056570] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [44.056725] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [44.056913] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [44.057133] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] 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> [44.057309] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [44.057473] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [44.057661] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [44.057913] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [44.058122] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [44.058280] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [44.058436] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [44.058658] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [44.058867] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [44.059043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [44.059219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [44.059376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [44.059592] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [44.059780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [44.059936] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [44.060108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [44.060446] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [44.070623] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [44.082032] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [44.082744] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [44.082914] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [44.083557] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [44.083800] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [44.083815] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [44.084429] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [44.087300] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [44.087577] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [44.087810] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [44.087986] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [44.088227] 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> [44.088649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [44.088828] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [44.088987] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [44.089160] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [44.089317] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [44.089472] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [44.089626] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [44.089785] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [44.089968] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [44.090211] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [44.090434] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [44.090655] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [44.090871] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [44.091117] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [44.091345] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [44.091559] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [44.091771] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [44.091984] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [44.092224] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [44.092439] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [44.092639] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [44.092799] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [44.093273] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [44.093564] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [44.093808] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [44.093996] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [44.094826] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [44.094998] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [44.095219] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [44.095396] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [44.095437] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [44.095530] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [44.095745] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [44.095933] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [44.096163] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [44.096336] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [44.096534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [44.096709] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [44.096873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [44.097057] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [44.097281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [44.097446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [44.097603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [44.097762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [44.097917] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [44.098089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [44.098247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [44.098404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [44.098560] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [44.098718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [44.098873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [44.099120] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [44.099285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [44.099441] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [44.099597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [44.099754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [44.099910] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [44.100082] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [44.100268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [44.100424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [44.100580] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [44.100736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [44.100891] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [44.101061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [44.101239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [44.101394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [44.101548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [44.101703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [44.101857] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [44.102021] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [44.102203] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [44.102358] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [44.102512] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [44.102667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [44.102821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [44.102976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [44.103149] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.103327] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.103482] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.103484] i915 0000:00:02.0: colorspace: RGB
<7> [44.103486] i915 0000:00:02.0: scan mode: Underscan
<7> [44.103487] i915 0000:00:02.0: colorimetry: No Data
<7> [44.103489] i915 0000:00:02.0: picture aspect: No Data
<7> [44.103490] i915 0000:00:02.0: active aspect: Same as Picture
<7> [44.103492] i915 0000:00:02.0: itc: No Data
<7> [44.103493] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [44.103495] i915 0000:00:02.0: quantization range: Full
<7> [44.103496] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [44.103498] i915 0000:00:02.0: video code: 0
<7> [44.103499] i915 0000:00:02.0: ycc quantization range: Full
<7> [44.103500] i915 0000:00:02.0: hdmi content type: Graphics
<7> [44.103502] i915 0000:00:02.0: pixel repeat: 0
<7> [44.103503] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [44.103505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [44.103667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.103826] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.103983] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.103985] i915 0000:00:02.0: vendor: Intel
<7> [44.103986] i915 0000:00:02.0: product: Integrated gfx
<7> [44.103988] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [44.103989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [44.104164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [44.104357] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [44.104514] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.104516] i915 0000:00:02.0: empty frame
<7> [44.104518] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [44.104711] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [44.104869] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [44.105124] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [44.105282] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [44.105435] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [44.105587] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [44.105743] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [44.105897] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [44.106097] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [44.106287] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [44.106448] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [44.106626] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [44.106804] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [44.106992] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [44.107169] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [44.107353] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [44.107514] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [44.107701] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [44.107869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [44.108043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [44.108227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [44.108387] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [44.108546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [44.108704] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [44.108861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [44.109027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [44.109205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [44.109361] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [44.109363] i915 0000:00:02.0: colorspace: RGB
<7> [44.109365] i915 0000:00:02.0: scan mode: Underscan
<7> [44.109366] i915 0000:00:02.0: colorimetry: No Data
<7> [44.109368] i915 0000:00:02.0: picture aspect: No Data
<7> [44.109369] i915 0000:00:02.0: active aspect: Same as Picture
<7> [44.109370] i915 0000:00:02.0: itc: No Data
<7> [44.109372] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [44.109373] i915 0000:00:02.0: quantization range: Full
<7> [44.109374] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [44.109376] i915 0000:00:02.0: video code: 0
<7> [44.109377] i915 0000:00:02.0: ycc quantization range: Full
<7> [44.109379] i915 0000:00:02.0: hdmi content type: Graphics
<7> [44.109380] i915 0000:00:02.0: pixel repeat: 0
<7> [44.109381] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [44.109383] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [44.109385] i915 0000:00:02.0: vendor: Intel
<7> [44.109386] i915 0000:00:02.0: product: Integrated gfx
<7> [44.109388] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [44.109389] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [44.109391] i915 0000:00:02.0: empty frame
<7> [44.109393] 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> [44.109550] 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> [44.109705] 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> [44.109860] 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> [44.110027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [44.110219] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [44.110375] 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> [44.110531] 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> [44.110685] 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> [44.110840] 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> [44.111003] 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> [44.111200] 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> [44.111386] 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> [44.111548] 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> [44.111709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [44.111865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [44.112031] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [44.112208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [44.112363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [44.112518] 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> [44.112674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [44.112830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [44.112986] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [44.113178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.113361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.113515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.113670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [44.113825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [44.113980] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.114157] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.114332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [44.114486] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [44.114641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [44.114795] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [44.114951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [44.115119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [44.115295] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [44.115449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [44.115603] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [44.115756] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [44.115910] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [44.116078] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [44.116256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [44.117807] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [44.117991] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [44.118219] 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> [44.118484] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [44.118666] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [44.118825] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [44.118983] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [44.119159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [44.119343] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [44.119501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [44.119657] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [44.119813] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [44.119967] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [44.120141] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [44.120317] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [44.120477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [44.120634] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [44.120790] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [44.120944] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [44.121117] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [44.121292] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [44.121446] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [44.121600] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [44.121754] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [44.122064] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [44.122357] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [44.122544] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [44.122767] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [44.123042] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [44.123547] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [44.124222] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [44.124370] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [44.125016] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [44.125170] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [44.125186] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [44.129044] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [44.129219] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [44.133819] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [44.133941] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [44.138584] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [44.138631] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [44.142139] 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> [44.142520] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [44.142775] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [44.142998] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [44.143029] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [44.143033] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [44.143036] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [44.143251] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [44.143607] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [44.974504] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [44.974531] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [44.974530] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [44.974530] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [44.975368] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [44.975879] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [55.052756] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [55.053039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [55.053622] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [55.054101] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [55.054603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [55.055074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [55.055568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [55.056033] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [55.056527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [55.056989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [55.057449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [55.057956] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [55.058413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [55.058924] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [55.059393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [55.059906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [55.060248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [55.060404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [55.060568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [55.060724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [55.060879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [55.061034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [55.061189] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [55.061348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [55.061503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [55.061673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [55.061828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [55.061984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [55.062138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [55.062294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [55.062449] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [55.062616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [55.062772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [55.062928] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [55.063083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [55.063237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [55.063392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [55.063548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [55.063718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [55.063874] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [55.064030] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [55.064186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [55.064341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.064495] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [55.064498] i915 0000:00:02.0: colorspace: RGB
<7> [55.064499] i915 0000:00:02.0: scan mode: Underscan
<7> [55.064501] i915 0000:00:02.0: colorimetry: No Data
<7> [55.064502] i915 0000:00:02.0: picture aspect: No Data
<7> [55.064504] i915 0000:00:02.0: active aspect: Same as Picture
<7> [55.064505] i915 0000:00:02.0: itc: No Data
<7> [55.064506] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [55.064508] i915 0000:00:02.0: quantization range: Full
<7> [55.064509] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [55.064511] i915 0000:00:02.0: video code: 0
<7> [55.064512] i915 0000:00:02.0: ycc quantization range: Full
<7> [55.064513] i915 0000:00:02.0: hdmi content type: Graphics
<7> [55.064515] i915 0000:00:02.0: pixel repeat: 0
<7> [55.064516] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [55.064518] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.064684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [55.064841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.064996] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [55.064998] i915 0000:00:02.0: vendor: Intel
<7> [55.065000] i915 0000:00:02.0: product: Integrated gfx
<7> [55.065001] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [55.065003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.065158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [55.065319] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.065478] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [55.065480] i915 0000:00:02.0: empty frame
<7> [55.065481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.065651] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [55.065811] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [55.066001] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [55.066157] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [55.066347] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [55.066501] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [55.066666] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [55.066821] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [55.066975] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [55.067128] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] 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> [55.067305] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [55.067469] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [55.067659] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [55.067840] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [55.068025] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [55.068183] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [55.068340] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [55.068501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [55.068668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [55.068828] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [55.068985] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [55.069143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [55.069300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [55.069458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [55.069615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [55.069784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [55.070017] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [55.085574] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [55.095891] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [55.096650] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [55.096902] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [55.097577] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [55.097864] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [55.097883] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [55.098113] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [55.103292] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [55.103522] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [55.103756] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [55.103928] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [55.104099] 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> [55.104388] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [55.104671] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [55.104902] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [55.105133] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [55.105498] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [55.105723] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [55.105946] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [55.106170] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [55.106520] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [55.106748] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [55.106973] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [55.107197] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [55.107511] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [55.107737] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [55.107967] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [55.108200] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [55.108459] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [55.108684] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [55.108909] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [55.109135] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [55.109375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [55.109598] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [55.110114] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [55.110436] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [55.110697] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [55.110970] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [55.111946] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [55.112221] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [55.112475] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [55.112732] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [55.112784] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [55.112900] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [55.113159] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [55.113401] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [55.113668] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [55.113890] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [55.114126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [55.114356] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [55.114611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [55.114841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [55.115074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [55.115302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [55.115549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [55.115783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [55.116011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [55.116241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [55.116472] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [55.116738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [55.116966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [55.117198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [55.117424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [55.117670] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [55.117901] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [55.118128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [55.118354] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [55.118597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [55.118829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [55.119056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [55.119285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [55.119516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [55.119780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [55.120007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [55.120236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [55.120466] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [55.120713] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [55.120952] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [55.121184] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [55.121414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [55.121658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [55.121884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [55.122116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [55.122346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [55.122576] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [55.122835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [55.123064] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [55.123293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [55.123524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.123772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.123995] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [55.123999] i915 0000:00:02.0: colorspace: RGB
<7> [55.124002] i915 0000:00:02.0: scan mode: Underscan
<7> [55.124005] i915 0000:00:02.0: colorimetry: No Data
<7> [55.124008] i915 0000:00:02.0: picture aspect: No Data
<7> [55.124010] i915 0000:00:02.0: active aspect: Same as Picture
<7> [55.124013] i915 0000:00:02.0: itc: No Data
<7> [55.124016] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [55.124019] i915 0000:00:02.0: quantization range: Full
<7> [55.124022] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [55.124025] i915 0000:00:02.0: video code: 0
<7> [55.124028] i915 0000:00:02.0: ycc quantization range: Full
<7> [55.124030] i915 0000:00:02.0: hdmi content type: Graphics
<7> [55.124033] i915 0000:00:02.0: pixel repeat: 0
<7> [55.124036] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [55.124040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [55.124268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.124493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.124736] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [55.124739] i915 0000:00:02.0: vendor: Intel
<7> [55.124742] i915 0000:00:02.0: product: Integrated gfx
<7> [55.124745] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [55.124748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [55.124975] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [55.125201] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [55.125426] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [55.125429] i915 0000:00:02.0: empty frame
<7> [55.125433] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [55.125711] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [55.125941] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [55.126235] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [55.126462] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [55.126696] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [55.126920] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [55.127145] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [55.127368] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [55.127625] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [55.127699] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [55.127896] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [55.128130] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [55.128182] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [55.128393] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [55.128622] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [55.128656] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [55.128969] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [55.129221] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [55.129453] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [55.129683] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [55.129955] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [55.130196] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [55.130429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [55.130660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [55.130916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [55.131150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [55.131382] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [55.131611] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [55.131862] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [55.132090] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [55.132320] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [55.132323] i915 0000:00:02.0: colorspace: RGB
<7> [55.132326] i915 0000:00:02.0: scan mode: Underscan
<7> [55.132329] i915 0000:00:02.0: colorimetry: No Data
<7> [55.132332] i915 0000:00:02.0: picture aspect: No Data
<7> [55.132335] i915 0000:00:02.0: active aspect: Same as Picture
<7> [55.132337] i915 0000:00:02.0: itc: No Data
<7> [55.132340] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [55.132343] i915 0000:00:02.0: quantization range: Full
<7> [55.132346] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [55.132349] i915 0000:00:02.0: video code: 0
<7> [55.132351] i915 0000:00:02.0: ycc quantization range: Full
<7> [55.132354] i915 0000:00:02.0: hdmi content type: Graphics
<7> [55.132357] i915 0000:00:02.0: pixel repeat: 0
<7> [55.132360] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [55.132363] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [55.132366] i915 0000:00:02.0: vendor: Intel
<7> [55.132369] i915 0000:00:02.0: product: Integrated gfx
<7> [55.132372] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [55.132375] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [55.132378] i915 0000:00:02.0: empty frame
<7> [55.132381] 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> [55.132609] 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> [55.132854] 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> [55.133083] 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> [55.133348] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [55.133385] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [55.133312] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [55.133537] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [55.133769] 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> [55.134030] 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> [55.134262] 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> [55.134492] 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> [55.134722] 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> [55.134974] 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> [55.135203] 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> [55.135433] 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> [55.135659] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [55.135901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [55.136125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [55.136353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [55.136579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [55.136809] 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> [55.137197] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [55.137425] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [55.137651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [55.137934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.138162] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.138393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.138617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [55.138841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [55.139095] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.139321] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.139549] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [55.139777] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [55.140025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [55.140251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [55.140482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [55.140710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [55.140963] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [55.140945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [55.141165] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [55.141175] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [55.141401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [55.141631] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [55.141861] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [55.142122] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [55.142359] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [55.142820] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [55.143097] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [55.143353] 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> [55.143676] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [55.143911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [55.144175] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [55.144406] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [55.144638] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [55.144863] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [55.145115] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [55.145338] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [55.145562] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [55.145789] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [55.146022] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [55.146248] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [55.146474] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [55.146698] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [55.146927] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [55.147176] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [55.147404] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [55.147628] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [55.147852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [55.148089] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [55.148318] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [55.148794] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [55.149141] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [55.149382] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [55.150030] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [55.150399] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [55.150889] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [55.151548] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [55.151767] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [55.152397] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [55.152632] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [55.152658] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [55.157073] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [55.157262] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [55.161369] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [55.169730] 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> [55.169919] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [55.170081] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [55.170249] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [55.170252] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [55.170253] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [55.170255] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [55.170393] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [55.170716] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [55.215057] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [55.219331] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [55.219367] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [63.082908] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-02-27 02:55:46