Results for igt@kms_flip@plain-flip-ts-check@d-hdmi-a1

Result: Fail

git-log-oneline i915_display_info17 igt_runner17 runtimes17 results17.json results17-i915-load.json guc_logs17.tar i915_display_info_post_exec17 boot17 dmesg17

DetailValue
Duration 18.59 seconds
Hostname 
shard-tglu-2
Igt-Version 
IGT-Version: 2.4-g152be5156 (x86_64) (Linux: 7.0.0-CI_DRM_18356-g9917a06970fd+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8340.4us +- 2.529us accuracy 0.09%
vblank interval differs from modeline! expected 8334.6us, measured 8340us +- 2.529us, difference 5.9us (2.3 sigma)
Event flip: expected 899, counted 897, passrate = 84.39%, encoder type 2
Expected frametime: 8335us; measured 8430.1us +- 3.296us accuracy 0.12%
vblank interval differs from modeline! expected 8334.6us, measured 8430us +- 3.296us, difference 95.5us (29.0 sigma)
Event flip: expected 890, counted 894, passrate = 62.75%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A1: FAIL (18.594s)
Subtest plain-flip-ts-check: FAIL (41.997s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A1
(kms_flip:2401) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2401) CRITICAL: Failed assertion: !retried
Dynamic subtest D-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2401) DEBUG: flip ts/seq: last 279.595947/2687, current 279.604340/2688: elapsed=8380.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.595947/2687, current 279.604340/2688: elapsed=8380.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.604340
last_received_ts = 279.604187
last_seq = 2688
current_ts = 279.612701
current_received_ts = 279.612427
current_seq = 2689
count = 814
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.604340/2688, current 279.612701/2689: elapsed=8370.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.604340/2688, current 279.612701/2689: elapsed=8370.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.612701
last_received_ts = 279.612427
last_seq = 2689
current_ts = 279.621063
current_received_ts = 279.620758
current_seq = 2690
count = 815
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.612701/2689, current 279.621063/2690: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.612701/2689, current 279.621063/2690: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.621063
last_received_ts = 279.620758
last_seq = 2690
current_ts = 279.629456
current_received_ts = 279.629120
current_seq = 2691
count = 816
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.621063/2690, current 279.629456/2691: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.621063/2690, current 279.629456/2691: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.629456
last_received_ts = 279.629120
last_seq = 2691
current_ts = 279.637817
current_received_ts = 279.637634
current_seq = 2692
count = 817
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.629456/2691, current 279.637817/2692: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.629456/2691, current 279.637817/2692: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.637817
last_received_ts = 279.637634
last_seq = 2692
current_ts = 279.646210
current_received_ts = 279.645844
current_seq = 2693
count = 818
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.637817/2692, current 279.646210/2693: elapsed=8377.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.637817/2692, current 279.646210/2693: elapsed=8377.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.646210
last_received_ts = 279.645844
last_seq = 2693
current_ts = 279.654572
current_received_ts = 279.654236
current_seq = 2694
count = 819
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.646210/2693, current 279.654572/2694: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.646210/2693, current 279.654572/2694: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.654572
last_received_ts = 279.654236
last_seq = 2694
current_ts = 279.662933
current_received_ts = 279.662598
current_seq = 2695
count = 820
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.654572/2694, current 279.662933/2695: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.654572/2694, current 279.662933/2695: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.662933
last_received_ts = 279.662598
last_seq = 2695
current_ts = 279.671326
current_received_ts = 279.671112
current_seq = 2696
count = 821
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.662933/2695, current 279.671326/2696: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.662933/2695, current 279.671326/2696: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.671326
last_received_ts = 279.671112
last_seq = 2696
current_ts = 279.679688
current_received_ts = 279.679352
current_seq = 2697
count = 822
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.671326/2696, current 279.679688/2697: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.671326/2696, current 279.679688/2697: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.679688
last_received_ts = 279.679352
last_seq = 2697
current_ts = 279.688080
current_received_ts = 279.687897
current_seq = 2698
count = 823
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.679688/2697, current 279.688080/2698: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.679688/2697, current 279.688080/2698: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.688080
last_received_ts = 279.687897
last_seq = 2698
current_ts = 279.696442
current_received_ts = 279.696106
current_seq = 2699
count = 824
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.688080/2698, current 279.696442/2699: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.688080/2698, current 279.696442/2699: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.696442
last_received_ts = 279.696106
last_seq = 2699
current_ts = 279.704803
current_received_ts = 279.704651
current_seq = 2700
count = 825
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.696442/2699, current 279.704803/2700: elapsed=8372.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.696442/2699, current 279.704803/2700: elapsed=8372.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.704803
last_received_ts = 279.704651
last_seq = 2700
current_ts = 279.713196
current_received_ts = 279.713043
current_seq = 2701
count = 826
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.704803/2700, current 279.713196/2701: elapsed=8380.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.704803/2700, current 279.713196/2701: elapsed=8380.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.713196
last_received_ts = 279.713043
last_seq = 2701
current_ts = 279.721558
current_received_ts = 279.721313
current_seq = 2702
count = 827
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.713196/2701, current 279.721558/2702: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.713196/2701, current 279.721558/2702: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.721558
last_received_ts = 279.721313
last_seq = 2702
current_ts = 279.729950
current_received_ts = 279.729614
current_seq = 2703
count = 828
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.721558/2702, current 279.729950/2703: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.721558/2702, current 279.729950/2703: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.729950
last_received_ts = 279.729614
last_seq = 2703
current_ts = 279.738312
current_received_ts = 279.738129
current_seq = 2704
count = 829
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.729950/2703, current 279.738312/2704: elapsed=8379.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.729950/2703, current 279.738312/2704: elapsed=8379.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.738312
last_received_ts = 279.738129
last_seq = 2704
current_ts = 279.746674
current_received_ts = 279.746338
current_seq = 2705
count = 830
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.738312/2704, current 279.746674/2705: elapsed=8370.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.738312/2704, current 279.746674/2705: elapsed=8370.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.746674
last_received_ts = 279.746338
last_seq = 2705
current_ts = 279.755066
current_received_ts = 279.754822
current_seq = 2706
count = 831
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.746674/2705, current 279.755066/2706: elapsed=8380.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.746674/2705, current 279.755066/2706: elapsed=8380.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.755066
last_received_ts = 279.754822
last_seq = 2706
current_ts = 279.763428
current_received_ts = 279.763153
current_seq = 2707
count = 832
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.755066/2706, current 279.763428/2707: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.755066/2706, current 279.763428/2707: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.763428
last_received_ts = 279.763153
last_seq = 2707
current_ts = 279.771820
current_received_ts = 279.771637
current_seq = 2708
count = 833
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.763428/2707, current 279.771820/2708: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.763428/2707, current 279.771820/2708: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.771820
last_received_ts = 279.771637
last_seq = 2708
current_ts = 279.780182
current_received_ts = 279.779968
current_seq = 2709
count = 834
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.771820/2708, current 279.780182/2709: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.771820/2708, current 279.780182/2709: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.780182
last_received_ts = 279.779968
last_seq = 2709
current_ts = 279.788574
current_received_ts = 279.788269
current_seq = 2710
count = 835
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.780182/2709, current 279.788574/2710: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.780182/2709, current 279.788574/2710: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.788574
last_received_ts = 279.788269
last_seq = 2710
current_ts = 279.796936
current_received_ts = 279.796722
current_seq = 2711
count = 836
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.788574/2710, current 279.796936/2711: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.788574/2710, current 279.796936/2711: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.796936
last_received_ts = 279.796722
last_seq = 2711
current_ts = 279.805298
current_received_ts = 279.805145
current_seq = 2712
count = 837
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.796936/2711, current 279.805298/2712: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.796936/2711, current 279.805298/2712: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.805298
last_received_ts = 279.805145
last_seq = 2712
current_ts = 279.813690
current_received_ts = 279.813324
current_seq = 2713
count = 838
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.805298/2712, current 279.813690/2713: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.805298/2712, current 279.813690/2713: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.813690
last_received_ts = 279.813324
last_seq = 2713
current_ts = 279.822052
current_received_ts = 279.821808
current_seq = 2714
count = 839
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.813690/2713, current 279.822052/2714: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.813690/2713, current 279.822052/2714: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.822052
last_received_ts = 279.821808
last_seq = 2714
current_ts = 279.830444
current_received_ts = 279.830078
current_seq = 2715
count = 840
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.822052/2714, current 279.830444/2715: elapsed=8372.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.822052/2714, current 279.830444/2715: elapsed=8372.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.830444
last_received_ts = 279.830078
last_seq = 2715
current_ts = 279.838806
current_received_ts = 279.838470
current_seq = 2716
count = 841
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.830444/2715, current 279.838806/2716: elapsed=8379.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.830444/2715, current 279.838806/2716: elapsed=8379.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.838806
last_received_ts = 279.838470
last_seq = 2716
current_ts = 279.847168
current_received_ts = 279.846893
current_seq = 2717
count = 842
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.838806/2716, current 279.847168/2717: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.838806/2716, current 279.847168/2717: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.847168
last_received_ts = 279.846893
last_seq = 2717
current_ts = 279.855560
current_received_ts = 279.855255
current_seq = 2718
count = 843
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.847168/2717, current 279.855560/2718: elapsed=8377.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.847168/2717, current 279.855560/2718: elapsed=8377.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.855560
last_received_ts = 279.855255
last_seq = 2718
current_ts = 279.863922
current_received_ts = 279.863708
current_seq = 2719
count = 844
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.855560/2718, current 279.863922/2719: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.855560/2718, current 279.863922/2719: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.863922
last_received_ts = 279.863708
last_seq = 2719
current_ts = 279.872314
current_received_ts = 279.872040
current_seq = 2720
count = 845
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.863922/2719, current 279.872314/2720: elapsed=8369.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.863922/2719, current 279.872314/2720: elapsed=8369.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.872314
last_received_ts = 279.872040
last_seq = 2720
current_ts = 279.880676
current_received_ts = 279.880432
current_seq = 2721
count = 846
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.872314/2720, current 279.880676/2721: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.872314/2720, current 279.880676/2721: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.880676
last_received_ts = 279.880432
last_seq = 2721
current_ts = 279.889069
current_received_ts = 279.888824
current_seq = 2722
count = 847
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.880676/2721, current 279.889069/2722: elapsed=8377.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.880676/2721, current 279.889069/2722: elapsed=8377.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.889069
last_received_ts = 279.888824
last_seq = 2722
current_ts = 279.897430
current_received_ts = 279.897186
current_seq = 2723
count = 848
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.889069/2722, current 279.897430/2723: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.889069/2722, current 279.897430/2723: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.897430
last_received_ts = 279.897186
last_seq = 2723
current_ts = 279.905792
current_received_ts = 279.905487
current_seq = 2724
count = 849
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.897430/2723, current 279.905792/2724: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.897430/2723, current 279.905792/2724: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.905792
last_received_ts = 279.905487
last_seq = 2724
current_ts = 279.914185
current_received_ts = 279.913818
current_seq = 2725
count = 850
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.905792/2724, current 279.914185/2725: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.905792/2724, current 279.914185/2725: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.914185
last_received_ts = 279.913818
last_seq = 2725
current_ts = 279.922546
current_received_ts = 279.922272
current_seq = 2726
count = 851
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.914185/2725, current 279.922546/2726: elapsed=8377.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.914185/2725, current 279.922546/2726: elapsed=8377.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.922546
last_received_ts = 279.922272
last_seq = 2726
current_ts = 279.930939
current_received_ts = 279.930573
current_seq = 2727
count = 852
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.922546/2726, current 279.930939/2727: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.922546/2726, current 279.930939/2727: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.930939
last_received_ts = 279.930573
last_seq = 2727
current_ts = 279.939301
current_received_ts = 279.939117
current_seq = 2728
count = 853
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.930939/2727, current 279.939301/2728: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.930939/2727, current 279.939301/2728: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.939301
last_received_ts = 279.939117
last_seq = 2728
current_ts = 279.947693
current_received_ts = 279.947388
current_seq = 2729
count = 854
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.939301/2728, current 279.947693/2729: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.939301/2728, current 279.947693/2729: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.947693
last_received_ts = 279.947388
last_seq = 2729
current_ts = 279.956055
current_received_ts = 279.955719
current_seq = 2730
count = 855
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.947693/2729, current 279.956055/2730: elapsed=8379.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.947693/2729, current 279.956055/2730: elapsed=8379.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.956055
last_received_ts = 279.955719
last_seq = 2730
current_ts = 279.964417
current_received_ts = 279.964142
current_seq = 2731
count = 856
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.956055/2730, current 279.964417/2731: elapsed=8370.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.956055/2730, current 279.964417/2731: elapsed=8370.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.964417
last_received_ts = 279.964142
last_seq = 2731
current_ts = 279.972809
current_received_ts = 279.972504
current_seq = 2732
count = 857
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.964417/2731, current 279.972809/2732: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.964417/2731, current 279.972809/2732: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.972809
last_received_ts = 279.972504
last_seq = 2732
current_ts = 279.981171
current_received_ts = 279.980896
current_seq = 2733
count = 858
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.972809/2732, current 279.981171/2733: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.972809/2732, current 279.981171/2733: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.981171
last_received_ts = 279.980896
last_seq = 2733
current_ts = 279.989563
current_received_ts = 279.989197
current_seq = 2734
count = 859
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.981171/2733, current 279.989563/2734: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.981171/2733, current 279.989563/2734: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.989563
last_received_ts = 279.989197
last_seq = 2734
current_ts = 279.997925
current_received_ts = 279.997711
current_seq = 2735
count = 860
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.989563/2734, current 279.997925/2735: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.989563/2734, current 279.997925/2735: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 279.997925
last_received_ts = 279.997711
last_seq = 2735
current_ts = 280.006317
current_received_ts = 280.006042
current_seq = 2736
count = 861
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 279.997925/2735, current 280.006317/2736: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 279.997925/2735, current 280.006317/2736: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.006317
last_received_ts = 280.006042
last_seq = 2736
current_ts = 280.014679
current_received_ts = 280.014374
current_seq = 2737
count = 862
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.006317/2736, current 280.014679/2737: elapsed=8372.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.006317/2736, current 280.014679/2737: elapsed=8372.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.014679
last_received_ts = 280.014374
last_seq = 2737
current_ts = 280.023041
current_received_ts = 280.022766
current_seq = 2738
count = 863
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.014679/2737, current 280.023041/2738: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.014679/2737, current 280.023041/2738: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.023041
last_received_ts = 280.022766
last_seq = 2738
current_ts = 280.031433
current_received_ts = 280.031097
current_seq = 2739
count = 864
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.023041/2738, current 280.031433/2739: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.023041/2738, current 280.031433/2739: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.031433
last_received_ts = 280.031097
last_seq = 2739
current_ts = 280.039795
current_received_ts = 280.039520
current_seq = 2740
count = 865
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.031433/2739, current 280.039795/2740: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.031433/2739, current 280.039795/2740: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.039795
last_received_ts = 280.039520
last_seq = 2740
current_ts = 280.048187
current_received_ts = 280.047974
current_seq = 2741
count = 866
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.039795/2740, current 280.048187/2741: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.039795/2740, current 280.048187/2741: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.048187
last_received_ts = 280.047974
last_seq = 2741
current_ts = 280.056549
current_received_ts = 280.056274
current_seq = 2742
count = 867
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.048187/2741, current 280.056549/2742: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.048187/2741, current 280.056549/2742: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.056549
last_received_ts = 280.056274
last_seq = 2742
current_ts = 280.064911
current_received_ts = 280.064667
current_seq = 2743
count = 868
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.056549/2742, current 280.064911/2743: elapsed=8372.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.056549/2742, current 280.064911/2743: elapsed=8372.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.064911
last_received_ts = 280.064667
last_seq = 2743
current_ts = 280.073303
current_received_ts = 280.073059
current_seq = 2744
count = 869
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.064911/2743, current 280.073303/2744: elapsed=8380.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.064911/2743, current 280.073303/2744: elapsed=8380.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.073303
last_received_ts = 280.073059
last_seq = 2744
current_ts = 280.081665
current_received_ts = 280.081360
current_seq = 2745
count = 870
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.073303/2744, current 280.081665/2745: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.073303/2744, current 280.081665/2745: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.081665
last_received_ts = 280.081360
last_seq = 2745
current_ts = 280.090057
current_received_ts = 280.089844
current_seq = 2746
count = 871
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.081665/2745, current 280.090057/2746: elapsed=8369.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.081665/2745, current 280.090057/2746: elapsed=8369.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.090057
last_received_ts = 280.089844
last_seq = 2746
current_ts = 280.098419
current_received_ts = 280.098236
current_seq = 2747
count = 872
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.090057/2746, current 280.098419/2747: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.090057/2746, current 280.098419/2747: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.098419
last_received_ts = 280.098236
last_seq = 2747
current_ts = 280.106812
current_received_ts = 280.106598
current_seq = 2748
count = 873
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.098419/2747, current 280.106812/2748: elapsed=8379.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.098419/2747, current 280.106812/2748: elapsed=8379.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.106812
last_received_ts = 280.106598
last_seq = 2748
current_ts = 280.115173
current_received_ts = 280.114929
current_seq = 2749
count = 874
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.106812/2748, current 280.115173/2749: elapsed=8368.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.106812/2748, current 280.115173/2749: elapsed=8368.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.115173
last_received_ts = 280.114929
last_seq = 2749
current_ts = 280.123535
current_received_ts = 280.123260
current_seq = 2750
count = 875
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.115173/2749, current 280.123535/2750: elapsed=8380.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.115173/2749, current 280.123535/2750: elapsed=8380.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.123535
last_received_ts = 280.123260
last_seq = 2750
current_ts = 280.131927
current_received_ts = 280.131744
current_seq = 2751
count = 876
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.123535/2750, current 280.131927/2751: elapsed=8372.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.123535/2750, current 280.131927/2751: elapsed=8372.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.131927
last_received_ts = 280.131744
last_seq = 2751
current_ts = 280.140289
current_received_ts = 280.139954
current_seq = 2752
count = 877
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.131927/2751, current 280.140289/2752: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.131927/2751, current 280.140289/2752: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.140289
last_received_ts = 280.139954
last_seq = 2752
current_ts = 280.148682
current_received_ts = 280.148438
current_seq = 2753
count = 878
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.140289/2752, current 280.148682/2753: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.140289/2752, current 280.148682/2753: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.148682
last_received_ts = 280.148438
last_seq = 2753
current_ts = 280.157043
current_received_ts = 280.156830
current_seq = 2754
count = 879
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.148682/2753, current 280.157043/2754: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.148682/2753, current 280.157043/2754: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.157043
last_received_ts = 280.156830
last_seq = 2754
current_ts = 280.165405
current_received_ts = 280.165100
current_seq = 2755
count = 880
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.157043/2754, current 280.165405/2755: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.157043/2754, current 280.165405/2755: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.165405
last_received_ts = 280.165100
last_seq = 2755
current_ts = 280.173798
current_received_ts = 280.173615
current_seq = 2756
count = 881
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.165405/2755, current 280.173798/2756: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.165405/2755, current 280.173798/2756: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.173798
last_received_ts = 280.173615
last_seq = 2756
current_ts = 280.182159
current_received_ts = 280.181976
current_seq = 2757
count = 882
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.173798/2756, current 280.182159/2757: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.173798/2756, current 280.182159/2757: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.182159
last_received_ts = 280.181976
last_seq = 2757
current_ts = 280.190552
current_received_ts = 280.190308
current_seq = 2758
count = 883
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.182159/2757, current 280.190552/2758: elapsed=8371.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.182159/2757, current 280.190552/2758: elapsed=8371.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.190552
last_received_ts = 280.190308
last_seq = 2758
current_ts = 280.198914
current_received_ts = 280.198578
current_seq = 2759
count = 884
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.190552/2758, current 280.198914/2759: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.190552/2758, current 280.198914/2759: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.198914
last_received_ts = 280.198578
last_seq = 2759
current_ts = 280.207275
current_received_ts = 280.207153
current_seq = 2760
count = 885
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.198914/2759, current 280.207275/2760: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.198914/2759, current 280.207275/2760: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.207275
last_received_ts = 280.207153
last_seq = 2760
current_ts = 280.215668
current_received_ts = 280.215302
current_seq = 2761
count = 886
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.207275/2760, current 280.215668/2761: elapsed=8375.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.207275/2760, current 280.215668/2761: elapsed=8375.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.215668
last_received_ts = 280.215302
last_seq = 2761
current_ts = 280.224030
current_received_ts = 280.223846
current_seq = 2762
count = 887
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.215668/2761, current 280.224030/2762: elapsed=8377.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.215668/2761, current 280.224030/2762: elapsed=8377.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.224030
last_received_ts = 280.223846
last_seq = 2762
current_ts = 280.232422
current_received_ts = 280.232208
current_seq = 2763
count = 888
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.224030/2762, current 280.232422/2763: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.224030/2762, current 280.232422/2763: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.232422
last_received_ts = 280.232208
last_seq = 2763
current_ts = 280.240784
current_received_ts = 280.240631
current_seq = 2764
count = 889
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.232422/2763, current 280.240784/2764: elapsed=8376.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.232422/2763, current 280.240784/2764: elapsed=8376.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.240784
last_received_ts = 280.240631
last_seq = 2764
current_ts = 280.249176
current_received_ts = 280.248993
current_seq = 2765
count = 890
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.240784/2764, current 280.249176/2765: elapsed=8370.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.240784/2764, current 280.249176/2765: elapsed=8370.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.249176
last_received_ts = 280.248993
last_seq = 2765
current_ts = 280.257538
current_received_ts = 280.257263
current_seq = 2766
count = 891
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.249176/2765, current 280.257538/2766: elapsed=8373.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.249176/2765, current 280.257538/2766: elapsed=8373.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.257538
last_received_ts = 280.257263
last_seq = 2766
current_ts = 280.265900
current_received_ts = 280.265717
current_seq = 2767
count = 892
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.257538/2766, current 280.265900/2767: elapsed=8378.0us expected=8430.1us +- 42.2us, error 0.6%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.257538/2766, current 280.265900/2767: elapsed=8378.0us expected=8430.1us
(kms_flip:2401) DEBUG: name = flip
last_ts = 280.265900
last_received_ts = 280.265717
last_seq = 2767
current_ts = 280.274292
current_received_ts = 280.273956
current_seq = 2768
count = 893
seq_step = 1
(kms_flip:2401) DEBUG: flip ts/seq: last 280.265900/2767, current 280.274292/2768: elapsed=8374.0us expected=8430.1us +- 42.2us, error 0.7%
(kms_flip:2401) DEBUG: inconsistent flip ts/seq: last 280.265900/2767, current 280.274292/2768: elapsed=8374.0us expected=8430.1us
(kms_flip:2401) INFO: Event flip: expected 890, counted 894, passrate = 62.75%, encoder type 2
(kms_flip:2401) DEBUG: dropped frames, expected 890, counted 894, passrate = 62.75%, encoder type 2
(kms_flip:2401) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2401) CRITICAL: Failed assertion: !retried
(kms_flip:2401) igt_core-INFO: Stack trace:
(kms_flip:2401) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2401) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:2401) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2401) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:2401) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2401) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2401) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2401) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A1: FAIL (18.594s)
Subtest plain-flip-ts-check: FAIL (41.997s)
(kms_flip:2401) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2401) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2401) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2401) igt_kms-CRITICAL: DPMS property not found on 588
Dmesg
<6> [336.775565] [IGT] kms_flip: starting dynamic subtest D-HDMI-A1
<7> [336.776427] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [336.776780] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:638]
<7> [336.822343] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [336.822811] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [336.823093] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [336.823271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [336.823790] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [336.824232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [336.824710] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [336.825138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [336.825529] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [336.825887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [336.826219] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [336.826724] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [336.827054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [336.827387] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [336.827757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [336.828116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [336.828389] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [336.828681] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [336.828955] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [336.829213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [336.829482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [336.829762] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [336.830023] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [336.830281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [336.830562] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [336.830836] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [336.831099] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [336.831296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [336.831504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [336.831715] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [336.831912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [336.832110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [336.832307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [336.832515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [336.832726] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [336.832925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [336.833122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [336.833321] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [336.833558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [336.833770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [336.833966] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [336.834140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [336.834295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [336.834450] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in avi infoframe
<7> [336.834627] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.834792] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [336.834795] i915 0000:00:02.0: colorspace: RGB
<7> [336.834796] i915 0000:00:02.0: scan mode: Underscan
<7> [336.834798] i915 0000:00:02.0: colorimetry: No Data
<7> [336.834799] i915 0000:00:02.0: picture aspect: No Data
<7> [336.834801] i915 0000:00:02.0: active aspect: Same as Picture
<7> [336.834802] i915 0000:00:02.0: itc: No Data
<7> [336.834803] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [336.834805] i915 0000:00:02.0: quantization range: Full
<7> [336.834806] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [336.834808] i915 0000:00:02.0: video code: 0
<7> [336.834809] i915 0000:00:02.0: ycc quantization range: Full
<7> [336.834811] i915 0000:00:02.0: hdmi content type: Graphics
<7> [336.834812] i915 0000:00:02.0: pixel repeat: 0
<7> [336.834814] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [336.834816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.834978] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in spd infoframe
<7> [336.835139] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.835298] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [336.835300] i915 0000:00:02.0: vendor: Intel
<7> [336.835301] i915 0000:00:02.0: product: Integrated gfx
<7> [336.835303] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [336.835305] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.835462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:447:pipe C] fastset requirement not met in hdmi infoframe
<7> [336.835640] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.835822] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [336.835824] i915 0000:00:02.0: empty frame
<7> [336.835825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.835983] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:447:pipe C] fastset requirement not met, forcing full modeset
<7> [336.836141] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:447:pipe C] releasing DPLL 0
<7> [336.836327] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [336.836495] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:447:pipe C] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [336.836704] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:441:cursor C] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [336.836883] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:447:pipe C] min cdclk: 192000 kHz -> 0 kHz
<7> [336.837074] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [336.837234] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [336.837392] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [336.837594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:447:pipe C] enable: no [modeset]
<7> [336.837761] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:311:plane 1C] fb: [NOFB], visible: no
<7> [336.837920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:341:plane 2C] fb: [NOFB], visible: no
<7> [336.838077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 3C] fb: [NOFB], visible: no
<7> [336.838234] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:401:plane 4C] fb: [NOFB], visible: no
<7> [336.838391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:411:plane 5C] fb: [NOFB], visible: no
<7> [336.838569] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:421:plane 6C] fb: [NOFB], visible: no
<7> [336.838742] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:431:plane 7C] fb: [NOFB], visible: no
<7> [336.838902] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:441:cursor C] fb: [NOFB], visible: no
<7> [336.839139] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:447:pipe C]
<7> [336.856040] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [336.858382] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [336.858995] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [336.859158] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [336.859721] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [336.859903] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [336.859920] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [336.860111] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe C
<7> [336.864516] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [336.864730] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:447:pipe C]
<7> [336.864924] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [336.865102] 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> [336.865356] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [336.865569] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [336.865739] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [336.865900] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [336.866058] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [336.866217] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [336.866375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [336.866563] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [336.866731] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [336.866888] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [336.867045] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [336.867208] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [336.867369] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [336.867562] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [336.867729] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [336.867889] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [336.868048] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [336.868210] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [336.868377] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [336.868556] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [336.868729] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [336.868892] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [336.869216] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [336.869428] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [336.869653] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [336.869840] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [336.870031] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:447:pipe C]
<7> [336.870504] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [336.870688] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [336.870720] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [336.870794] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [336.870975] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [336.871133] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [336.871298] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:585:pipe D]
<7> [336.871484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [336.871661] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [336.871823] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [336.871983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [336.872141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [336.872301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [336.872471] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [336.872642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [336.872799] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [336.872956] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [336.873113] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [336.873269] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [336.873425] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [336.873601] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [336.873771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [336.873927] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [336.874083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [336.874238] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [336.874393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [336.874579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [336.874745] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [336.874900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [336.875054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [336.875209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [336.875364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [336.875594] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [336.875811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [336.875984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [336.876154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [336.876325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [336.876524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [336.876696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [336.876865] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [336.877035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [336.877205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [336.877375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [336.877580] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [336.877749] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [336.877919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [336.878088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [336.878256] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [336.878424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.878656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.878823] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [336.878826] i915 0000:00:02.0: colorspace: RGB
<7> [336.878828] i915 0000:00:02.0: scan mode: Underscan
<7> [336.878830] i915 0000:00:02.0: colorimetry: No Data
<7> [336.878832] i915 0000:00:02.0: picture aspect: No Data
<7> [336.878834] i915 0000:00:02.0: active aspect: Same as Picture
<7> [336.878836] i915 0000:00:02.0: itc: No Data
<7> [336.878838] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [336.878840] i915 0000:00:02.0: quantization range: Full
<7> [336.878842] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [336.878844] i915 0000:00:02.0: video code: 0
<7> [336.878846] i915 0000:00:02.0: ycc quantization range: Full
<7> [336.878848] i915 0000:00:02.0: hdmi content type: Graphics
<7> [336.878850] i915 0000:00:02.0: pixel repeat: 0
<7> [336.878852] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [336.878854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [336.879029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.879202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.879371] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [336.879374] i915 0000:00:02.0: vendor: Intel
<7> [336.879376] i915 0000:00:02.0: product: Integrated gfx
<7> [336.879378] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [336.879380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [336.879587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [336.879756] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [336.879923] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [336.879926] i915 0000:00:02.0: empty frame
<7> [336.879929] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [336.880141] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [336.880312] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [336.880559] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [336.880724] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [336.880888] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [336.881050] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [336.881213] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [336.881375] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [336.881601] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 1142000 num active planes 1
<7> [336.881777] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [336.881949] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [336.882142] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [336.882373] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [336.882587] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [336.882757] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [336.882928] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [336.883104] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [336.883282] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [336.883464] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [336.883637] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [336.883806] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [336.883975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [336.884143] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [336.884311] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [336.884502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [336.884671] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [336.884839] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [336.884841] i915 0000:00:02.0: colorspace: RGB
<7> [336.884843] i915 0000:00:02.0: scan mode: Underscan
<7> [336.884845] i915 0000:00:02.0: colorimetry: No Data
<7> [336.884847] i915 0000:00:02.0: picture aspect: No Data
<7> [336.884849] i915 0000:00:02.0: active aspect: Same as Picture
<7> [336.884851] i915 0000:00:02.0: itc: No Data
<7> [336.884853] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [336.884855] i915 0000:00:02.0: quantization range: Full
<7> [336.884857] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [336.884858] i915 0000:00:02.0: video code: 0
<7> [336.884860] i915 0000:00:02.0: ycc quantization range: Full
<7> [336.884862] i915 0000:00:02.0: hdmi content type: Graphics
<7> [336.884864] i915 0000:00:02.0: pixel repeat: 0
<7> [336.884866] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [336.884868] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [336.884870] i915 0000:00:02.0: vendor: Intel
<7> [336.884872] i915 0000:00:02.0: product: Integrated gfx
<7> [336.884874] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [336.884876] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [336.884878] i915 0000:00:02.0: empty frame
<7> [336.884880] 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> [336.885054] 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> [336.885224] 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> [336.885393] 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> [336.885615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [336.885784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [336.885953] 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> [336.886123] 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> [336.886289] 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> [336.886463] 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> [336.886634] 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> [336.886803] 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> [336.886971] 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> [336.887138] 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> [336.887307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [336.887495] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [336.887662] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [336.887829] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [336.887995] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [336.888161] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [336.888327] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [336.888529] 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> [336.888562] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [336.888706] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [336.888842] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [336.888897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [336.889086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [336.889138] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [336.889266] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.889455] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.889633] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.889803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [336.889975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [336.890146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.890316] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.890405] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [336.890523] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [336.890700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [336.890869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [336.891039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [336.891207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [336.891376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [336.891585] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [336.891757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [336.891925] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [336.892093] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [336.892262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [336.892433] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [336.893218] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [336.893415] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [336.894178] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [336.895378] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [336.895616] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [336.895806] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [336.895993] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [336.896209] 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> [336.896481] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [336.896662] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [336.896833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [336.897008] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [336.897178] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [336.897347] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [336.897551] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [336.897722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [336.897891] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [336.898062] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [336.898232] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [336.898398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [336.898623] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [336.898791] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [336.898958] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [336.899128] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [336.899294] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [336.899481] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [336.899650] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [336.899816] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [336.899980] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [336.900318] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [336.900572] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [336.900751] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [336.900964] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [336.901229] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [336.902013] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [336.902012] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [336.902666] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [336.902907] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [336.903583] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [336.903756] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [336.903772] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [336.907004] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [336.907045] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [336.912435] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:585:pipe D], 64 bytes ELD
<7> [336.920786] 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> [336.920980] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [336.921143] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [336.921312] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [336.921314] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [336.921316] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [336.921318] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [336.921471] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [336.921732] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [347.527882] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [347.528444] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe B]
<7> [347.528919] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe C]
<7> [347.529368] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [347.529582] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [347.530213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [347.530793] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [347.531311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [347.531832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [347.532333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [347.532848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [347.533339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [347.533658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [347.533860] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [347.534018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [347.534175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [347.534332] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [347.534489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [347.534652] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [347.534832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [347.535007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [347.535165] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [347.535324] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [347.535482] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [347.535639] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [347.535811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [347.535981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [347.536137] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [347.536292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [347.536448] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [347.536603] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [347.536780] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [347.536946] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [347.537102] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [347.537258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [347.537413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [347.537567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [347.537764] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [347.537925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [347.538081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [347.538237] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [347.538392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [347.538547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [347.538714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [347.538884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [347.539039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.539193] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.539195] i915 0000:00:02.0: colorspace: RGB
<7> [347.539197] i915 0000:00:02.0: scan mode: Underscan
<7> [347.539198] i915 0000:00:02.0: colorimetry: No Data
<7> [347.539200] i915 0000:00:02.0: picture aspect: No Data
<7> [347.539201] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.539203] i915 0000:00:02.0: itc: No Data
<7> [347.539204] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.539206] i915 0000:00:02.0: quantization range: Full
<7> [347.539207] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.539209] i915 0000:00:02.0: video code: 0
<7> [347.539210] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.539211] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.539213] i915 0000:00:02.0: pixel repeat: 0
<7> [347.539214] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.539216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.539371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [347.539525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.539692] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.539696] i915 0000:00:02.0: vendor: Intel
<7> [347.539699] i915 0000:00:02.0: product: Integrated gfx
<7> [347.539702] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.539705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.539885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [347.540040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.540193] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.540195] i915 0000:00:02.0: empty frame
<7> [347.540196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.540351] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [347.540507] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [347.540717] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.540880] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [347.541076] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [347.541233] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [347.541389] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [347.541542] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.541715] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.541889] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [347.542068] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [347.542233] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [347.542395] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [347.542573] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [347.542777] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [347.542968] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.543127] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [347.543284] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.543444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [347.543605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [347.543784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [347.543956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [347.544112] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [347.544270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [347.544426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [347.544583] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [347.544765] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [347.545007] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:585:pipe D]
<7> [347.555742] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [347.557482] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [347.558028] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.558183] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.558700] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.558865] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [347.558881] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [347.559117] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [347.564418] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [347.564687] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [347.564906] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [347.565084] 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> [347.565315] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [347.565527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [347.565694] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [347.565855] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [347.566017] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [347.566177] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [347.566336] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [347.566527] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [347.566736] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [347.566897] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [347.567060] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [347.567224] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [347.567401] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [347.567573] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [347.567733] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [347.567894] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [347.568059] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [347.568278] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [347.568516] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [347.568742] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [347.568969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [347.569189] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [347.569729] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [347.570018] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [347.570273] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [347.570546] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [347.570753] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [347.571020] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [347.571512] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [347.571550] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:588:HDMI-A-1]
<7> [347.571655] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [347.571841] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [347.572008] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [347.572175] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:585:pipe D]
<7> [347.572362] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [347.572524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [347.572685] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [347.572849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [347.573052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [347.573216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [347.573397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [347.573565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [347.573721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [347.573878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [347.574034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [347.574191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [347.574397] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [347.574612] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [347.574772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [347.574971] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [347.575195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [347.575440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [347.575660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [347.575885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [347.576116] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [347.576373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [347.576591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [347.576809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [347.577032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [347.577266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [347.577491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [347.577650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [347.577805] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [347.577960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [347.578115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [347.578307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [347.578513] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [347.578671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [347.578834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [347.578992] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [347.579153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [347.579323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [347.579506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [347.579662] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [347.579828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [347.579983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.580181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.580449] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.580453] i915 0000:00:02.0: colorspace: RGB
<7> [347.580455] i915 0000:00:02.0: scan mode: Underscan
<7> [347.580458] i915 0000:00:02.0: colorimetry: No Data
<7> [347.580460] i915 0000:00:02.0: picture aspect: No Data
<7> [347.580463] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.580465] i915 0000:00:02.0: itc: No Data
<7> [347.580468] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.580471] i915 0000:00:02.0: quantization range: Full
<7> [347.580474] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.580475] i915 0000:00:02.0: video code: 0
<7> [347.580477] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.580478] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.580480] i915 0000:00:02.0: pixel repeat: 0
<7> [347.580481] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.580483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [347.580658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.580821] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.580982] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.580984] i915 0000:00:02.0: vendor: Intel
<7> [347.580985] i915 0000:00:02.0: product: Integrated gfx
<7> [347.580987] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.580989] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [347.581150] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [347.581329] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [347.581520] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.581522] i915 0000:00:02.0: empty frame
<7> [347.581525] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [347.581731] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [347.581895] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [347.582130] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [347.582348] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [347.582573] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [347.582785] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [347.582938] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [347.583102] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [347.583294] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 1142000 num active planes 1
<7> [347.583458] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [347.583618] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [347.583839] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 0 kHz -> 192000 kHz
<7> [347.584026] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [347.584227] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [347.584394] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [347.584549] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [347.584707] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:585:pipe D] allocated DPLL 0
<7> [347.584872] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:585:pipe D] reserving DPLL 0
<7> [347.585070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: yes [modeset]
<7> [347.585247] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [347.585415] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [347.585580] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [347.585738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [347.585895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [347.586050] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [347.586257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [347.586416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [347.586580] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [347.586582] i915 0000:00:02.0: colorspace: RGB
<7> [347.586583] i915 0000:00:02.0: scan mode: Underscan
<7> [347.586585] i915 0000:00:02.0: colorimetry: No Data
<7> [347.586586] i915 0000:00:02.0: picture aspect: No Data
<7> [347.586588] i915 0000:00:02.0: active aspect: Same as Picture
<7> [347.586590] i915 0000:00:02.0: itc: No Data
<7> [347.586591] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [347.586592] i915 0000:00:02.0: quantization range: Full
<7> [347.586594] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [347.586595] i915 0000:00:02.0: video code: 0
<7> [347.586597] i915 0000:00:02.0: ycc quantization range: Full
<7> [347.586598] i915 0000:00:02.0: hdmi content type: Graphics
<7> [347.586600] i915 0000:00:02.0: pixel repeat: 0
<7> [347.586601] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [347.586603] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [347.586605] i915 0000:00:02.0: vendor: Intel
<7> [347.586606] i915 0000:00:02.0: product: Integrated gfx
<7> [347.586607] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [347.586609] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [347.586611] i915 0000:00:02.0: empty frame
<7> [347.586612] 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> [347.586777] 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> [347.586932] 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> [347.587087] 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> [347.587265] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [347.587459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [347.587645] 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> [347.587802] 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> [347.587964] 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> [347.588138] 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> [347.588321] 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> [347.588483] 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> [347.588644] 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> [347.588846] 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> [347.589005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [347.589185] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [347.589207] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [347.589375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [347.589406] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [347.589538] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [347.589580] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [347.589702] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [347.589869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [347.590026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [347.590201] 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> [347.590378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [347.590540] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [347.590694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.590764] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [347.590859] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.591018] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.591208] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.591363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.591517] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [347.591692] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.591879] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.592038] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [347.592214] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [347.592397] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [347.592556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [347.592710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [347.592888] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [347.593065] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [347.593256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [347.593422] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [347.593582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [347.593739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [347.593897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [347.594227] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [347.594399] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [347.595101] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [347.596292] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [347.596472] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [347.596646] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_4
<7> [347.596815] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_5
<7> [347.597019] 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> [347.597273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [347.597437] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [347.597599] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [347.597759] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [347.597918] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [347.598113] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [347.598322] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [347.598483] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [347.598653] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [347.598820] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [347.598983] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [347.599162] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [347.599321] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [347.599532] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [347.599700] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [347.599866] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [347.600036] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [347.600200] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [347.600357] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [347.600513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [347.600669] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [347.600956] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [347.601190] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x8, on? 0) for [CRTC:585:pipe D]
<7> [347.601374] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [347.601612] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [347.601861] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [347.602603] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [347.603197] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.603344] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [347.603942] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [347.604103] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [347.604119] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [347.612751] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:585:pipe D], 64 bytes ELD
<7> [347.621075] 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> [347.621270] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [347.621433] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [347.621601] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [347.621604] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [347.621605] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [347.621607] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [347.621765] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [347.622024] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [347.622902] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [347.627252] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [347.627287] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [355.369880] [IGT] kms_flip: finished subtest D-HDMI-A1, FAIL
<6> [355.372115] [IGT] kms_flip: finished subtest plain-flip-ts-check, FAIL
<7> [355.379792] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:638] from all active usage due to RMFB ioctl
<7> [355.379851] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:449:plane 1D] because [FB:638] is removed
<7> [355.379899] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:449:plane 1D] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [355.380097] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [355.380259] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.380417] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.380575] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:449:plane 1D] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [355.380783] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:585:pipe D] data rate 0 num active planes 0
<7> [355.380964] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [355.381127] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [355.390063] [IGT] kms_flip: exiting, ret=98
<7> [355.403667] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:588:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [355.403883] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [355.404054] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [355.404243] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:587:DDI B/PHY B] [CRTC:171:pipe A]
<7> [355.404406] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [355.404583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [355.404747] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [355.404908] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [355.405068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [355.405228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [355.405386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [355.405545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [355.405718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [355.405875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [355.406037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [355.406195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [355.406364] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [355.406572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [355.406730] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [355.406896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [355.407062] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [355.407253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [355.407410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [355.407678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [355.407844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [355.408038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [355.408196] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [355.408360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [355.408524] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [355.408822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [355.408986] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [355.409142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [355.409314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [355.409494] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [355.409674] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [355.409832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [355.410006] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [355.410183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [355.410346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [355.410504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [355.410699] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [355.410871] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [355.411037] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [355.411194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [355.411377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in avi infoframe
<7> [355.411657] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.411822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.411992] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [355.411996] i915 0000:00:02.0: colorspace: RGB
<7> [355.412000] i915 0000:00:02.0: scan mode: Underscan
<7> [355.412002] i915 0000:00:02.0: colorimetry: No Data
<7> [355.412006] i915 0000:00:02.0: picture aspect: No Data
<7> [355.412009] i915 0000:00:02.0: active aspect: Same as Picture
<7> [355.412012] i915 0000:00:02.0: itc: No Data
<7> [355.412015] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [355.412019] i915 0000:00:02.0: quantization range: Full
<7> [355.412022] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [355.412025] i915 0000:00:02.0: video code: 0
<7> [355.412029] i915 0000:00:02.0: ycc quantization range: Full
<7> [355.412031] i915 0000:00:02.0: hdmi content type: Graphics
<7> [355.412035] i915 0000:00:02.0: pixel repeat: 0
<7> [355.412038] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [355.412040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in spd infoframe
<7> [355.412215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.412379] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.413285] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [355.413287] i915 0000:00:02.0: vendor: Intel
<7> [355.413289] i915 0000:00:02.0: product: Integrated gfx
<7> [355.413291] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [355.413293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hdmi infoframe
<7> [355.413479] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.413655] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.413821] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [355.413823] i915 0000:00:02.0: empty frame
<7> [355.413825] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [355.413991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [355.414180] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [355.414337] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [355.414501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [355.414683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [355.414868] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [355.415026] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [355.415188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [355.415353] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [355.415542] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [355.415704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [355.415861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [355.416034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [355.416213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [355.416377] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [355.416549] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [355.416736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [355.416907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [355.417071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [355.417228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [355.417412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [355.417598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [355.417761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [355.417925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [355.418115] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [355.418271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [355.418434] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [355.418638] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [355.418797] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [355.418953] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [355.419110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [355.419266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [355.419421] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [355.419596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [355.419752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [355.419911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [355.420074] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [355.420233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [355.420393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [355.420587] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [355.420748] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in avi infoframe
<7> [355.420906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.421063] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [355.421066] i915 0000:00:02.0: colorspace: RGB
<7> [355.421068] i915 0000:00:02.0: scan mode: Underscan
<7> [355.421070] i915 0000:00:02.0: colorimetry: No Data
<7> [355.421071] i915 0000:00:02.0: picture aspect: No Data
<7> [355.421073] i915 0000:00:02.0: active aspect: Same as Picture
<7> [355.421075] i915 0000:00:02.0: itc: No Data
<7> [355.421076] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [355.421078] i915 0000:00:02.0: quantization range: Full
<7> [355.421080] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [355.421082] i915 0000:00:02.0: video code: 0
<7> [355.421084] i915 0000:00:02.0: ycc quantization range: Full
<7> [355.421085] i915 0000:00:02.0: hdmi content type: Graphics
<7> [355.421087] i915 0000:00:02.0: pixel repeat: 0
<7> [355.421089] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [355.421091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.421248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in spd infoframe
<7> [355.421405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.421575] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [355.421577] i915 0000:00:02.0: vendor: Intel
<7> [355.421579] i915 0000:00:02.0: product: Integrated gfx
<7> [355.421581] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [355.421583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.421739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:585:pipe D] fastset requirement not met in hdmi infoframe
<7> [355.421896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [355.422053] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [355.422054] i915 0000:00:02.0: empty frame
<7> [355.422056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [355.422212] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:585:pipe D] fastset requirement not met, forcing full modeset
<7> [355.422371] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:585:pipe D] releasing DPLL 0
<7> [355.422602] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [355.422764] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:585:pipe D] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [355.422927] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [355.423085] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [355.423242] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [355.423399] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [355.423567] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [355.423723] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [355.423884] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:579:cursor D] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [355.424065] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 1142000 num active planes 1
<7> [355.424232] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [355.424393] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [355.424584] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [355.424746] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:585:pipe D] min cdclk: 192000 kHz -> 0 kHz
<7> [355.424926] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 0
<7> [355.425092] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 0
<7> [355.425260] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [355.425423] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [355.425599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [355.425758] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [355.425916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [355.426074] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [355.426233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [355.426390] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [355.426560] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [355.426718] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [355.426720] i915 0000:00:02.0: colorspace: RGB
<7> [355.426722] i915 0000:00:02.0: scan mode: Underscan
<7> [355.426723] i915 0000:00:02.0: colorimetry: No Data
<7> [355.426725] i915 0000:00:02.0: picture aspect: No Data
<7> [355.426727] i915 0000:00:02.0: active aspect: Same as Picture
<7> [355.426728] i915 0000:00:02.0: itc: No Data
<7> [355.426730] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [355.426732] i915 0000:00:02.0: quantization range: Full
<7> [355.426733] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [355.426735] i915 0000:00:02.0: video code: 0
<7> [355.426737] i915 0000:00:02.0: ycc quantization range: Full
<7> [355.426738] i915 0000:00:02.0: hdmi content type: Graphics
<7> [355.426740] i915 0000:00:02.0: pixel repeat: 0
<7> [355.426742] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [355.426744] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [355.426746] i915 0000:00:02.0: vendor: Intel
<7> [355.426747] i915 0000:00:02.0: product: Integrated gfx
<7> [355.426749] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [355.426751] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [355.426753] i915 0000:00:02.0: empty frame
<7> [355.426754] 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> [355.426917] 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> [355.427078] 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> [355.427237] 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> [355.427394] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [355.427565] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [355.427722] 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> [355.427880] 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> [355.428037] 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> [355.428192] 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> [355.428348] 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> [355.428515] 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> [355.428672] 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> [355.428828] 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> [355.428984] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pixel rate 285500, min cdclk 192000, min voltage level 0
<7> [355.429139] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [355.429293] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [355.429449] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [355.429639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [355.429794] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [355.429949] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [355.430105] 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> [355.430262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [355.430416] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [355.430588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [355.430744] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.430899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.431055] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.431209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [355.431364] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [355.431566] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.431738] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.431892] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [355.432046] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [355.432202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:635] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [355.432357] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [355.432525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [355.432681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [355.432836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [355.432991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [355.433147] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [355.433301] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [355.433466] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [355.433622] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [355.433777] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:585:pipe D] enable: no [modeset]
<7> [355.433932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 1D] fb: [NOFB], visible: no
<7> [355.434087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 2D] fb: [NOFB], visible: no
<7> [355.434241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:509:plane 3D] fb: [NOFB], visible: no
<7> [355.434396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:539:plane 4D] fb: [NOFB], visible: no
<7> [355.434892] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:549:plane 5D] fb: [NOFB], visible: no
<7> [355.435060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:559:plane 6D] fb: [NOFB], visible: no
<7> [355.435222] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:569:plane 7D] fb: [NOFB], visible: no
<7> [355.435383] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:579:cursor D] fb: [NOFB], visible: no
<7> [355.435713] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Disable audio codec on [CRTC:585:pipe D]
<7> [355.447277] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [355.449381] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [355.450063] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [355.450298] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [355.450966] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [355.451196] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [355.451222] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [355.451460] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [355.456371] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [355.456574] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x8, on? 1) for [CRTC:585:pipe D]
<7> [355.456767] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [355.456947] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI B/PHY B]
<7> [355.457111] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:597:DDI TC1/PHY TC1]
<7> [355.457273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST A]
<7> [355.457462] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST B]
<7> [355.457698] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST C]
<7> [355.457931] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:602:DP-MST D]
<7> [355.458161] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:607:DDI TC2/PHY TC2]
<7> [355.458419] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST A]
<7> [355.458587] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST B]
<7> [355.458749] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST C]
<7> [355.458910] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST D]
<7> [355.459093] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:616:DDI TC3/PHY TC3]
<7> [355.459334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST A]
<7> [355.459567] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST B]
<7> [355.459797] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST C]
<7> [355.460020] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DP-MST D]
<7> [355.460175] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DDI TC4/PHY TC4]
<7> [355.460348] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST A]
<7> [355.460504] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST B]
<7> [355.460694] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST C]
<7> [355.460923] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:630:DP-MST D]
<7> [355.461512] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [355.461692] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [355.461895] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [355.462151] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [355.463134] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:588:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [355.463720] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [355.463935] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [355.464589] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [355.464824] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:588:HDMI-A-1] Failed to read TMDS config: -6
<7> [355.464849] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:588:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [355.467956] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [355.468147] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [355.473543] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:588:HDMI-A-1][ENCODER:587:DDI B/PHY B] Enable audio codec on [CRTC:171:pipe A], 64 bytes ELD
<7> [355.481888] 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> [355.482072] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [355.482220] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [355.482516] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [355.482519] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [355.482521] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [355.482523] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [355.482672] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:588:HDMI-A-1]
<7> [355.482946] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [355.483879] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_5
<7> [355.484096] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:585:pipe D]
<7> [355.484531] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_4
<7> [355.484942] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-23 07:25:50