Results for igt@kms_flip@flip-vs-absolute-wf_vblank@a-hdmi-a1

Result: Fail

integration-manifest git-log-oneline i915_display_info20 igt_runner20 runtimes20 results20.json results20-i915-load.json i915_display_info_post_exec20 boot20 dmesg20

DetailValue
Duration 19.18 seconds
Hostname
shard-tglu-7
Igt-Version
IGT-Version: 2.2-geb100acf0 (x86_64) (Linux: 6.18.0-rc5-CI_DRM_17544-g9d61bbd3042d+ x86_64)
Out
Using IGT_SRANDOM=1763098821 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-absolute-wf_vblank
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5 
Expected frametime: 8335us; measured 8334.5us +- 3.967us accuracy 0.14%
Event flip: expected 906, counted 930, passrate = 20.43%, encoder type 2
Event vblank: expected 906, counted 930, passrate = 19.35%, encoder type 2
Expected frametime: 8335us; measured 8227.6us +- 3.881us accuracy 0.14%
vblank interval differs from modeline! expected 8334.6us, measured 8228us +- 3.881us, difference 107.0us (27.6 sigma)
Event flip: expected 914, counted 910, passrate = 50.55%, encoder type 2
Event vblank: expected 914, counted 910, passrate = 49.45%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (19.184s)
Err
Starting subtest: flip-vs-absolute-wf_vblank
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2012) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2012) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.360420
last_received_ts = 61.360168
last_seq = 2907
current_ts = 61.443138
current_received_ts = 61.442986
current_seq = 2917
count = 51
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.360420/2907, current 61.443138/2917: elapsed=82717.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.360420/2907, current 61.443138/2917: elapsed=82717.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.434864
last_received_ts = 61.434731
last_seq = 2916
current_ts = 61.517586
current_received_ts = 61.517517
current_seq = 2926
count = 51
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.434864/2916, current 61.517586/2926: elapsed=82721.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.434864/2916, current 61.517586/2926: elapsed=82721.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.443138
last_received_ts = 61.442986
last_seq = 2917
current_ts = 61.525860
current_received_ts = 61.525654
current_seq = 2927
count = 52
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.443138/2917, current 61.525860/2927: elapsed=82722.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.443138/2917, current 61.525860/2927: elapsed=82722.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.517586
last_received_ts = 61.517517
last_seq = 2926
current_ts = 61.600307
current_received_ts = 61.600231
current_seq = 2936
count = 52
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.517586/2926, current 61.600307/2936: elapsed=82724.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.517586/2926, current 61.600307/2936: elapsed=82724.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.525860
last_received_ts = 61.525654
last_seq = 2927
current_ts = 61.608582
current_received_ts = 61.608280
current_seq = 2937
count = 53
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.525860/2927, current 61.608582/2937: elapsed=82720.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.525860/2927, current 61.608582/2937: elapsed=82720.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.600307
last_received_ts = 61.600231
last_seq = 2936
current_ts = 61.683025
current_received_ts = 61.682957
current_seq = 2946
count = 53
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.600307/2936, current 61.683025/2946: elapsed=82715.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.600307/2936, current 61.683025/2946: elapsed=82715.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.608582
last_received_ts = 61.608280
last_seq = 2937
current_ts = 61.691303
current_received_ts = 61.691158
current_seq = 2947
count = 54
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.608582/2937, current 61.691303/2947: elapsed=82721.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.608582/2937, current 61.691303/2947: elapsed=82721.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.683025
last_received_ts = 61.682957
last_seq = 2946
current_ts = 61.765751
current_received_ts = 61.765675
current_seq = 2956
count = 54
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.683025/2946, current 61.765751/2956: elapsed=82726.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.683025/2946, current 61.765751/2956: elapsed=82726.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.691303
last_received_ts = 61.691158
last_seq = 2947
current_ts = 61.774017
current_received_ts = 61.773796
current_seq = 2957
count = 55
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.691303/2947, current 61.774017/2957: elapsed=82717.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.691303/2947, current 61.774017/2957: elapsed=82717.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.765751
last_received_ts = 61.765675
last_seq = 2956
current_ts = 61.848465
current_received_ts = 61.848389
current_seq = 2966
count = 55
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.765751/2956, current 61.848465/2966: elapsed=82715.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.765751/2956, current 61.848465/2966: elapsed=82715.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.774017
last_received_ts = 61.773796
last_seq = 2957
current_ts = 61.856739
current_received_ts = 61.856522
current_seq = 2967
count = 56
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.774017/2957, current 61.856739/2967: elapsed=82721.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.774017/2957, current 61.856739/2967: elapsed=82721.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.848465
last_received_ts = 61.848389
last_seq = 2966
current_ts = 61.931187
current_received_ts = 61.931110
current_seq = 2976
count = 56
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.848465/2966, current 61.931187/2976: elapsed=82722.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.848465/2966, current 61.931187/2976: elapsed=82722.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.856739
last_received_ts = 61.856522
last_seq = 2967
current_ts = 61.939465
current_received_ts = 61.939259
current_seq = 2977
count = 57
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.856739/2967, current 61.939465/2977: elapsed=82723.0us expected=82275.6us +- 411.4us, error 0.5%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.856739/2967, current 61.939465/2977: elapsed=82723.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 61.931187
last_received_ts = 61.931110
last_seq = 2976
current_ts = 62.013966
current_received_ts = 62.013905
current_seq = 2986
count = 57
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 61.931187/2976, current 62.013966/2986: elapsed=82779.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 61.931187/2976, current 62.013966/2986: elapsed=82779.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 61.939465
last_received_ts = 61.939259
last_seq = 2977
current_ts = 62.022251
current_received_ts = 62.022156
current_seq = 2987
count = 58
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 61.939465/2977, current 62.022251/2987: elapsed=82787.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 61.939465/2977, current 62.022251/2987: elapsed=82787.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.013966
last_received_ts = 62.013905
last_seq = 2986
current_ts = 62.096764
current_received_ts = 62.096695
current_seq = 2996
count = 58
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.013966/2986, current 62.096764/2996: elapsed=82797.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.013966/2986, current 62.096764/2996: elapsed=82797.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.022251
last_received_ts = 62.022156
last_seq = 2987
current_ts = 62.105045
current_received_ts = 62.104862
current_seq = 2997
count = 59
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.022251/2987, current 62.105045/2997: elapsed=82794.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.022251/2987, current 62.105045/2997: elapsed=82794.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.096764
last_received_ts = 62.096695
last_seq = 2996
current_ts = 62.179565
current_received_ts = 62.179420
current_seq = 3006
count = 59
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.096764/2996, current 62.179565/3006: elapsed=82801.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.096764/2996, current 62.179565/3006: elapsed=82801.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.105045
last_received_ts = 62.104862
last_seq = 2997
current_ts = 62.187843
current_received_ts = 62.187614
current_seq = 3007
count = 60
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.105045/2997, current 62.187843/3007: elapsed=82801.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.105045/2997, current 62.187843/3007: elapsed=82801.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.179565
last_received_ts = 62.179420
last_seq = 3006
current_ts = 62.262363
current_received_ts = 62.262287
current_seq = 3016
count = 60
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.179565/3006, current 62.262363/3016: elapsed=82798.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.179565/3006, current 62.262363/3016: elapsed=82798.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.187843
last_received_ts = 62.187614
last_seq = 3007
current_ts = 62.270638
current_received_ts = 62.270424
current_seq = 3017
count = 61
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.187843/3007, current 62.270638/3017: elapsed=82794.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.187843/3007, current 62.270638/3017: elapsed=82794.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.262363
last_received_ts = 62.262287
last_seq = 3016
current_ts = 62.345158
current_received_ts = 62.345112
current_seq = 3026
count = 61
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.262363/3016, current 62.345158/3026: elapsed=82797.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.262363/3016, current 62.345158/3026: elapsed=82797.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.270638
last_received_ts = 62.270424
last_seq = 3017
current_ts = 62.353439
current_received_ts = 62.353260
current_seq = 3027
count = 62
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.270638/3017, current 62.353439/3027: elapsed=82799.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.270638/3017, current 62.353439/3027: elapsed=82799.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.345158
last_received_ts = 62.345112
last_seq = 3026
current_ts = 62.427959
current_received_ts = 62.427879
current_seq = 3036
count = 62
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.345158/3026, current 62.427959/3036: elapsed=82802.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.345158/3026, current 62.427959/3036: elapsed=82802.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.353439
last_received_ts = 62.353260
last_seq = 3027
current_ts = 62.436241
current_received_ts = 62.436081
current_seq = 3037
count = 63
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.353439/3027, current 62.436241/3037: elapsed=82804.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.353439/3027, current 62.436241/3037: elapsed=82804.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.427959
last_received_ts = 62.427879
last_seq = 3036
current_ts = 62.510757
current_received_ts = 62.510624
current_seq = 3046
count = 63
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.427959/3036, current 62.510757/3046: elapsed=82797.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.427959/3036, current 62.510757/3046: elapsed=82797.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.436241
last_received_ts = 62.436081
last_seq = 3037
current_ts = 62.519035
current_received_ts = 62.518837
current_seq = 3047
count = 64
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.436241/3037, current 62.519035/3047: elapsed=82792.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.436241/3037, current 62.519035/3047: elapsed=82792.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.510757
last_received_ts = 62.510624
last_seq = 3046
current_ts = 62.593555
current_received_ts = 62.593483
current_seq = 3056
count = 64
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.510757/3046, current 62.593555/3056: elapsed=82799.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.510757/3046, current 62.593555/3056: elapsed=82799.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.519035
last_received_ts = 62.518837
last_seq = 3047
current_ts = 62.601837
current_received_ts = 62.601616
current_seq = 3057
count = 65
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.519035/3047, current 62.601837/3057: elapsed=82803.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.519035/3047, current 62.601837/3057: elapsed=82803.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.593555
last_received_ts = 62.593483
last_seq = 3056
current_ts = 62.676353
current_received_ts = 62.676109
current_seq = 3066
count = 65
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.593555/3056, current 62.676353/3066: elapsed=82796.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.593555/3056, current 62.676353/3066: elapsed=82796.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.601837
last_received_ts = 62.601616
last_seq = 3057
current_ts = 62.684635
current_received_ts = 62.684395
current_seq = 3067
count = 66
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.601837/3057, current 62.684635/3067: elapsed=82800.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.601837/3057, current 62.684635/3067: elapsed=82800.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.676353
last_received_ts = 62.676109
last_seq = 3066
current_ts = 62.759148
current_received_ts = 62.759121
current_seq = 3076
count = 66
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.676353/3066, current 62.759148/3076: elapsed=82796.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.676353/3066, current 62.759148/3076: elapsed=82796.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.684635
last_received_ts = 62.684395
last_seq = 3067
current_ts = 62.767433
current_received_ts = 62.767193
current_seq = 3077
count = 67
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.684635/3067, current 62.767433/3077: elapsed=82798.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.684635/3067, current 62.767433/3077: elapsed=82798.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.759148
last_received_ts = 62.759121
last_seq = 3076
current_ts = 62.841946
current_received_ts = 62.841820
current_seq = 3086
count = 67
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.759148/3076, current 62.841946/3086: elapsed=82798.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.759148/3076, current 62.841946/3086: elapsed=82798.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.767433
last_received_ts = 62.767193
last_seq = 3077
current_ts = 62.850231
current_received_ts = 62.849937
current_seq = 3087
count = 68
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.767433/3077, current 62.850231/3087: elapsed=82798.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.767433/3077, current 62.850231/3087: elapsed=82798.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.841946
last_received_ts = 62.841820
last_seq = 3086
current_ts = 62.924751
current_received_ts = 62.924675
current_seq = 3096
count = 68
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.841946/3086, current 62.924751/3096: elapsed=82804.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.841946/3086, current 62.924751/3096: elapsed=82804.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.850231
last_received_ts = 62.849937
last_seq = 3087
current_ts = 62.933033
current_received_ts = 62.932838
current_seq = 3097
count = 69
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.850231/3087, current 62.933033/3097: elapsed=82800.0us expected=82275.6us +- 411.4us, error 0.6%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.850231/3087, current 62.933033/3097: elapsed=82800.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 62.924751
last_received_ts = 62.924675
last_seq = 3096
current_ts = 63.007599
current_received_ts = 63.007282
current_seq = 3106
count = 69
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 62.924751/3096, current 63.007599/3106: elapsed=82847.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 62.924751/3096, current 63.007599/3106: elapsed=82847.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 62.933033
last_received_ts = 62.932838
last_seq = 3097
current_ts = 63.015877
current_received_ts = 63.015545
current_seq = 3107
count = 70
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 62.933033/3097, current 63.015877/3107: elapsed=82845.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 62.933033/3097, current 63.015877/3107: elapsed=82845.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.007599
last_received_ts = 63.007282
last_seq = 3106
current_ts = 63.090458
current_received_ts = 63.090389
current_seq = 3116
count = 70
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.007599/3106, current 63.090458/3116: elapsed=82861.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.007599/3106, current 63.090458/3116: elapsed=82861.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.015877
last_received_ts = 63.015545
last_seq = 3107
current_ts = 63.098747
current_received_ts = 63.098587
current_seq = 3117
count = 71
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.015877/3107, current 63.098747/3117: elapsed=82870.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.015877/3107, current 63.098747/3117: elapsed=82870.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.090458
last_received_ts = 63.090389
last_seq = 3116
current_ts = 63.173325
current_received_ts = 63.173248
current_seq = 3126
count = 71
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.090458/3116, current 63.173325/3126: elapsed=82866.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.090458/3116, current 63.173325/3126: elapsed=82866.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.098747
last_received_ts = 63.098587
last_seq = 3117
current_ts = 63.181618
current_received_ts = 63.181362
current_seq = 3127
count = 72
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.098747/3117, current 63.181618/3127: elapsed=82870.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.098747/3117, current 63.181618/3127: elapsed=82870.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.173325
last_received_ts = 63.173248
last_seq = 3126
current_ts = 63.256195
current_received_ts = 63.256123
current_seq = 3136
count = 72
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.173325/3126, current 63.256195/3136: elapsed=82871.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.173325/3126, current 63.256195/3136: elapsed=82871.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.181618
last_received_ts = 63.181362
last_seq = 3127
current_ts = 63.264484
current_received_ts = 63.264263
current_seq = 3137
count = 73
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.181618/3127, current 63.264484/3137: elapsed=82865.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.181618/3127, current 63.264484/3137: elapsed=82865.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.256195
last_received_ts = 63.256123
last_seq = 3136
current_ts = 63.339058
current_received_ts = 63.338985
current_seq = 3146
count = 73
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.256195/3136, current 63.339058/3146: elapsed=82863.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.256195/3136, current 63.339058/3146: elapsed=82863.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.264484
last_received_ts = 63.264263
last_seq = 3137
current_ts = 63.347351
current_received_ts = 63.347099
current_seq = 3147
count = 74
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.264484/3137, current 63.347351/3147: elapsed=82869.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.264484/3137, current 63.347351/3147: elapsed=82869.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.339058
last_received_ts = 63.338985
last_seq = 3146
current_ts = 63.421928
current_received_ts = 63.421852
current_seq = 3156
count = 74
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.339058/3146, current 63.421928/3156: elapsed=82871.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.339058/3146, current 63.421928/3156: elapsed=82871.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.347351
last_received_ts = 63.347099
last_seq = 3147
current_ts = 63.430210
current_received_ts = 63.429985
current_seq = 3157
count = 75
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.347351/3147, current 63.430210/3157: elapsed=82860.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.347351/3147, current 63.430210/3157: elapsed=82860.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.421928
last_received_ts = 63.421852
last_seq = 3156
current_ts = 63.504791
current_received_ts = 63.504719
current_seq = 3166
count = 75
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.421928/3156, current 63.504791/3166: elapsed=82862.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.421928/3156, current 63.504791/3166: elapsed=82862.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.430210
last_received_ts = 63.429985
last_seq = 3157
current_ts = 63.513084
current_received_ts = 63.512779
current_seq = 3167
count = 76
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.430210/3157, current 63.513084/3167: elapsed=82872.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.430210/3157, current 63.513084/3167: elapsed=82872.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.504791
last_received_ts = 63.504719
last_seq = 3166
current_ts = 63.587662
current_received_ts = 63.587585
current_seq = 3176
count = 76
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.504791/3166, current 63.587662/3176: elapsed=82871.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.504791/3166, current 63.587662/3176: elapsed=82871.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.513084
last_received_ts = 63.512779
last_seq = 3167
current_ts = 63.595951
current_received_ts = 63.595711
current_seq = 3177
count = 77
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.513084/3167, current 63.595951/3177: elapsed=82866.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.513084/3167, current 63.595951/3177: elapsed=82866.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.587662
last_received_ts = 63.587585
last_seq = 3176
current_ts = 63.670528
current_received_ts = 63.670452
current_seq = 3186
count = 77
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.587662/3176, current 63.670528/3186: elapsed=82865.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.587662/3176, current 63.670528/3186: elapsed=82865.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.595951
last_received_ts = 63.595711
last_seq = 3177
current_ts = 63.678818
current_received_ts = 63.678574
current_seq = 3187
count = 78
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.595951/3177, current 63.678818/3187: elapsed=82868.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.595951/3177, current 63.678818/3187: elapsed=82868.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.670528
last_received_ts = 63.670452
last_seq = 3186
current_ts = 63.753399
current_received_ts = 63.753326
current_seq = 3196
count = 78
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.670528/3186, current 63.753399/3196: elapsed=82870.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.670528/3186, current 63.753399/3196: elapsed=82870.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.678818
last_received_ts = 63.678574
last_seq = 3187
current_ts = 63.761684
current_received_ts = 63.761375
current_seq = 3197
count = 79
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.678818/3187, current 63.761684/3197: elapsed=82866.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.678818/3187, current 63.761684/3197: elapsed=82866.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.753399
last_received_ts = 63.753326
last_seq = 3196
current_ts = 63.836262
current_received_ts = 63.836201
current_seq = 3206
count = 79
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.753399/3196, current 63.836262/3206: elapsed=82865.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.753399/3196, current 63.836262/3206: elapsed=82865.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.761684
last_received_ts = 63.761375
last_seq = 3197
current_ts = 63.844547
current_received_ts = 63.844337
current_seq = 3207
count = 80
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.761684/3197, current 63.844547/3207: elapsed=82865.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.761684/3197, current 63.844547/3207: elapsed=82865.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.836262
last_received_ts = 63.836201
last_seq = 3206
current_ts = 63.919128
current_received_ts = 63.919106
current_seq = 3216
count = 80
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.836262/3206, current 63.919128/3216: elapsed=82866.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.836262/3206, current 63.919128/3216: elapsed=82866.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.844547
last_received_ts = 63.844337
last_seq = 3207
current_ts = 63.927418
current_received_ts = 63.927200
current_seq = 3217
count = 81
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.844547/3207, current 63.927418/3217: elapsed=82869.0us expected=82275.6us +- 411.4us, error 0.7%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.844547/3207, current 63.927418/3217: elapsed=82869.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 63.919128
last_received_ts = 63.919106
last_seq = 3216
current_ts = 64.002037
current_received_ts = 64.001968
current_seq = 3226
count = 81
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 63.919128/3216, current 64.002037/3226: elapsed=82906.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 63.919128/3216, current 64.002037/3226: elapsed=82906.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 63.927418
last_received_ts = 63.927200
last_seq = 3217
current_ts = 64.010323
current_received_ts = 64.010193
current_seq = 3227
count = 82
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 63.927418/3217, current 64.010323/3227: elapsed=82906.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 63.927418/3217, current 64.010323/3227: elapsed=82906.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.002037
last_received_ts = 64.001968
last_seq = 3226
current_ts = 64.084953
current_received_ts = 64.084778
current_seq = 3236
count = 82
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.002037/3226, current 64.084953/3236: elapsed=82920.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.002037/3226, current 64.084953/3236: elapsed=82920.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.010323
last_received_ts = 64.010193
last_seq = 3227
current_ts = 64.093246
current_received_ts = 64.092957
current_seq = 3237
count = 83
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.010323/3227, current 64.093246/3237: elapsed=82925.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.010323/3227, current 64.093246/3237: elapsed=82925.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.084953
last_received_ts = 64.084778
last_seq = 3236
current_ts = 64.167885
current_received_ts = 64.167809
current_seq = 3246
count = 83
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.084953/3236, current 64.167885/3246: elapsed=82929.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.084953/3236, current 64.167885/3246: elapsed=82929.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.093246
last_received_ts = 64.092957
last_seq = 3237
current_ts = 64.176178
current_received_ts = 64.175903
current_seq = 3247
count = 84
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.093246/3237, current 64.176178/3247: elapsed=82928.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.093246/3237, current 64.176178/3247: elapsed=82928.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.167885
last_received_ts = 64.167809
last_seq = 3246
current_ts = 64.250809
current_received_ts = 64.250595
current_seq = 3256
count = 84
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.167885/3246, current 64.250809/3256: elapsed=82925.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.167885/3246, current 64.250809/3256: elapsed=82925.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.176178
last_received_ts = 64.175903
last_seq = 3247
current_ts = 64.259102
current_received_ts = 64.258904
current_seq = 3257
count = 85
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.176178/3247, current 64.259102/3257: elapsed=82924.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.176178/3247, current 64.259102/3257: elapsed=82924.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.250809
last_received_ts = 64.250595
last_seq = 3256
current_ts = 64.333740
current_received_ts = 64.333664
current_seq = 3266
count = 85
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.250809/3256, current 64.333740/3266: elapsed=82931.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.250809/3256, current 64.333740/3266: elapsed=82931.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.259102
last_received_ts = 64.258904
last_seq = 3257
current_ts = 64.342033
current_received_ts = 64.341789
current_seq = 3267
count = 86
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.259102/3257, current 64.342033/3267: elapsed=82933.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.259102/3257, current 64.342033/3267: elapsed=82933.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.333740
last_received_ts = 64.333664
last_seq = 3266
current_ts = 64.416664
current_received_ts = 64.416588
current_seq = 3276
count = 86
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.333740/3266, current 64.416664/3276: elapsed=82926.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.333740/3266, current 64.416664/3276: elapsed=82926.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.342033
last_received_ts = 64.341789
last_seq = 3267
current_ts = 64.424957
current_received_ts = 64.424759
current_seq = 3277
count = 87
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.342033/3267, current 64.424957/3277: elapsed=82922.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.342033/3267, current 64.424957/3277: elapsed=82922.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.416664
last_received_ts = 64.416588
last_seq = 3276
current_ts = 64.499596
current_received_ts = 64.499519
current_seq = 3286
count = 87
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.416664/3276, current 64.499596/3286: elapsed=82926.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.416664/3276, current 64.499596/3286: elapsed=82926.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.424957
last_received_ts = 64.424759
last_seq = 3277
current_ts = 64.507881
current_received_ts = 64.507645
current_seq = 3287
count = 88
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.424957/3277, current 64.507881/3287: elapsed=82925.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.424957/3277, current 64.507881/3287: elapsed=82925.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.499596
last_received_ts = 64.499519
last_seq = 3286
current_ts = 64.582512
current_received_ts = 64.582443
current_seq = 3296
count = 88
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.499596/3286, current 64.582512/3296: elapsed=82923.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.499596/3286, current 64.582512/3296: elapsed=82923.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.507881
last_received_ts = 64.507645
last_seq = 3287
current_ts = 64.590813
current_received_ts = 64.590508
current_seq = 3297
count = 89
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.507881/3287, current 64.590813/3297: elapsed=82932.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.507881/3287, current 64.590813/3297: elapsed=82932.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.582512
last_received_ts = 64.582443
last_seq = 3296
current_ts = 64.665443
current_received_ts = 64.665359
current_seq = 3306
count = 89
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.582512/3296, current 64.665443/3306: elapsed=82929.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.582512/3296, current 64.665443/3306: elapsed=82929.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = flip
last_ts = 64.590813
last_received_ts = 64.590508
last_seq = 3297
current_ts = 64.673737
current_received_ts = 64.673424
current_seq = 3307
count = 90
seq_step = 1
(kms_flip:2012) DEBUG: flip ts/seq: last 64.590813/3297, current 64.673737/3307: elapsed=82924.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent flip ts/seq: last 64.590813/3297, current 64.673737/3307: elapsed=82924.0us expected=82275.6us
(kms_flip:2012) DEBUG: name = vblank
last_ts = 64.665443
last_received_ts = 64.665359
last_seq = 3306
current_ts = 64.748375
current_received_ts = 64.748299
current_seq = 3316
count = 90
seq_step = 10
(kms_flip:2012) DEBUG: vblank ts/seq: last 64.665443/3306, current 64.748375/3316: elapsed=82929.0us expected=82275.6us +- 411.4us, error 0.8%
(kms_flip:2012) DEBUG: inconsistent vblank ts/seq: last 64.665443/3306, current 64.748375/3316: elapsed=82929.0us expected=82275.6us
(kms_flip:2012) INFO: Event flip: expected 914, counted 910, passrate = 50.55%, encoder type 2
(kms_flip:2012) DEBUG: dropped frames, expected 914, counted 910, passrate = 50.55%, encoder type 2
(kms_flip:2012) INFO: Event vblank: expected 914, counted 910, passrate = 49.45%, encoder type 2
(kms_flip:2012) DEBUG: dropped frames, expected 914, counted 910, passrate = 49.45%, encoder type 2
(kms_flip:2012) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2012) CRITICAL: Failed assertion: !retried
(kms_flip:2012) igt_core-INFO: Stack trace:
(kms_flip:2012) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2012) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2012) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2012) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
(kms_flip:2012) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2012) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2012) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2012) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (19.184s)
Dmesg
<6> [46.031764] Console: switching to colour dummy device 80x25
<6> [46.031820] [IGT] kms_flip: executing
<7> [46.034336] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [46.035035] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [46.035349] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [46.045742] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:352:DP-1]
<7> [46.045751] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:352:DP-1]
<7> [46.050180] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:352:DP-1] disconnected
<7> [46.050550] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:362:DP-2]
<7> [46.050554] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:362:DP-2]
<7> [46.050867] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:362:DP-2] disconnected
<7> [46.051320] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:371:DP-3]
<7> [46.051326] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:371:DP-3]
<7> [46.051775] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:371:DP-3] disconnected
<7> [46.052449] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:380:DP-4]
<7> [46.052455] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:380:DP-4]
<7> [46.052917] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:380:DP-4] disconnected
<7> [46.053927] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:HDMI-A-1]
<7> [46.053940] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [46.080999] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [46.081014] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [46.081019] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] No deep color support on this HDMI sink.
<7> [46.081024] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [46.081029] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [46.081035] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [46.081040] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [46.081045] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:342:HDMI-A-1] ELD size 64, SAD count 10
<7> [46.081452] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [46.081661] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.082335] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [46.082566] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [46.083943] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:342:HDMI-A-1] probed modes:
<7> [46.083950] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 285500 1920 1968 2000 2080 1080 1083 1088 1144 0x48 0x5
<7> [46.083956] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 594000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083962] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 60 593407 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083967] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 297000 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083973] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4096x2160": 30 296703 4096 4184 4272 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083978] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 594000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083984] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 60 593407 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083989] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 297000 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.083994] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 30 296703 3840 4016 4104 4400 2160 2168 2178 2250 0x40 0x5
<7> [46.084000] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 25 297000 3840 4896 4984 5280 2160 2168 2178 2250 0x40 0x5
<7> [46.084005] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 297000 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [46.084011] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 24 296703 3840 5116 5204 5500 2160 2168 2178 2250 0x40 0x5
<7> [46.084017] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3440x1440": 90 476140 3440 3448 3480 3520 1440 1441 1449 1503 0x40 0xa
<7> [46.084022] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 75 443250 2560 2768 3048 3536 1600 1603 1609 1672 0x40 0x6
<7> [46.084028] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 60 348500 2560 2752 3032 3504 1600 1603 1609 1658 0x40 0x6
<7> [46.084033] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 120 497770 2560 2608 2640 2720 1440 1443 1448 1525 0x40 0xa
<7> [46.084039] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 60 267027 2048 2200 2424 2800 1536 1537 1540 1589 0x0 0x6
<7> [46.084044] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 495000 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [46.084050] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1080": 120 494505 2560 3108 3152 3300 1080 1084 1089 1250 0x40 0x5
<7> [46.084055] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 75 297000 1920 2064 2288 2640 1440 1441 1444 1500 0x40 0x6
<7> [46.084061] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1440": 60 234000 1920 2048 2256 2600 1440 1441 1444 1500 0x40 0x6
<7> [46.084066] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2160x1200": 90 261420 2160 2208 2240 2320 1200 1203 1208 1252 0x40 0xa
<7> [46.084071] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 75 288000 1856 1984 2208 2560 1392 1393 1396 1500 0x40 0x6
<7> [46.084077] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1856x1392": 60 218250 1856 1952 2176 2528 1392 1393 1396 1439 0x40 0x6
<7> [46.084083] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 75 261000 1792 1888 2104 2456 1344 1345 1348 1417 0x40 0x6
<7> [46.084088] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1792x1344": 60 204750 1792 1920 2120 2448 1344 1345 1348 1394 0x40 0x6
<7> [46.084107] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1152": 60 162000 2048 2074 2154 2250 1152 1153 1156 1200 0x40 0x5
<7> [46.084113] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 120 317250 1920 1968 2000 2080 1200 1203 1209 1271 0x40 0xa
<7> [46.084118] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 75 245250 1920 2056 2264 2608 1200 1203 1209 1255 0x40 0x6
<7> [46.084124] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 193250 1920 2056 2256 2592 1200 1203 1209 1245 0x40 0x6
<7> [46.084129] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [46.084134] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [46.084140] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 75 220597 1920 2056 2264 2608 1080 1081 1084 1128 0x0 0x6
<7> [46.084145] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [46.084150] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [46.084156] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 75 202500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [46.084161] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 70 189000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [46.084167] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 65 175500 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [46.084172] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5
<7> [46.084177] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 75 187000 1680 1800 1976 2272 1050 1053 1059 1099 0x40 0x6
<7> [46.084183] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6
<7> [46.084188] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x945": 60 131481 1680 1784 1960 2240 945 946 949 978 0x0 0x6
<7> [46.084194] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 75 156000 1400 1504 1648 1896 1050 1053 1057 1099 0x40 0x6
<7> [46.084199] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1400x1050": 60 121750 1400 1488 1632 1864 1050 1053 1057 1089 0x40 0x6
<7> [46.084205] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [46.084210] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [46.084215] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [46.084221] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 75 136750 1440 1536 1688 1936 900 903 909 942 0x40 0x6
<7> [46.084226] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 106500 1440 1520 1672 1904 900 903 909 934 0x40 0x6
<7> [46.084232] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x960": 60 108000 1280 1376 1488 1800 960 961 964 1000 0x40 0x5
<7> [46.084237] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6
<7> [46.084242] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 60 85500 1360 1424 1536 1792 768 771 777 795 0x40 0x5
<7> [46.084248] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 75 106500 1280 1360 1488 1696 800 803 809 838 0x40 0x6
<7> [46.084253] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6
<7> [46.084259] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [46.084264] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 75 102250 1280 1360 1488 1696 768 771 778 805 0x40 0x6
<7> [46.084269] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x768": 60 79500 1280 1344 1472 1664 768 771 778 798 0x40 0x6
<7> [46.084275] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [46.084280] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [46.084285] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [46.084291] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [46.084296] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46970 1024 1064 1168 1312 576 577 580 597 0x0 0x6
<7> [46.084301] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
<7> [46.084307] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [46.084312] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [46.084318] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [46.084323] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [46.084328] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "848x480": 60 33750 848 864 976 1088 480 486 494 517 0x40 0x5
<7> [46.084334] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [46.084339] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [46.084344] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [46.084350] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [46.084355] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
<7> [46.084421] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:352:DP-1]
<7> [46.084428] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:352:DP-1]
<7> [46.088937] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:352:DP-1] disconnected
<7> [46.088956] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:362:DP-2]
<7> [46.088959] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:362:DP-2]
<7> [46.089333] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:362:DP-2] disconnected
<7> [46.089348] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:371:DP-3]
<7> [46.089352] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:371:DP-3]
<7> [46.089671] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:371:DP-3] disconnected
<7> [46.089684] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:380:DP-4]
<7> [46.089687] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:380:DP-4]
<7> [46.090005] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:380:DP-4] disconnected
<6> [46.090503] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank
<6> [46.093220] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [46.093593] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:390]
<7> [46.093689] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:392]
<7> [46.139741] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [46.139854] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [46.140054] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [46.140314] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [46.140553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [46.140779] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [46.141007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [46.141255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [46.141480] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [46.141703] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [46.141926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [46.142164] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [46.142395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [46.142615] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [46.142837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [46.143063] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [46.143302] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [46.143483] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [46.143656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [46.143830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [46.144004] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [46.144195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [46.144370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [46.144544] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [46.144718] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [46.144892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [46.145065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [46.145260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [46.145439] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [46.145613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [46.145789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [46.145963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [46.146146] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [46.146303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [46.146458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [46.146614] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [46.146770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [46.146925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [46.147081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [46.147252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [46.147414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [46.147570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in avi infoframe
<7> [46.147725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.147879] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.147881] i915 0000:00:02.0: colorspace: RGB
<7> [46.147883] i915 0000:00:02.0: scan mode: Underscan
<7> [46.147885] i915 0000:00:02.0: colorimetry: No Data
<7> [46.147886] i915 0000:00:02.0: picture aspect: No Data
<7> [46.147888] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.147889] i915 0000:00:02.0: itc: No Data
<7> [46.147891] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.147892] i915 0000:00:02.0: quantization range: Full
<7> [46.147894] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.147895] i915 0000:00:02.0: video code: 0
<7> [46.147897] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.147898] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.147900] i915 0000:00:02.0: pixel repeat: 0
<7> [46.147901] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.147903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.148065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in spd infoframe
<7> [46.148240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.148404] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.148405] i915 0000:00:02.0: vendor: Intel
<7> [46.148407] i915 0000:00:02.0: product: Integrated gfx
<7> [46.148409] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.148410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.148569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hdmi infoframe
<7> [46.148727] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.148884] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.148886] i915 0000:00:02.0: empty frame
<7> [46.148887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.149044] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [46.149214] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:108:pipe A] releasing DPLL 0
<7> [46.149406] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [46.149566] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [46.149758] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [46.149915] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [46.150072] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [46.150244] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [46.150401] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [46.150557] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [46.150731] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 0 num active planes 0
<7> [46.150891] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [46.151068] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [46.151266] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [46.151451] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [46.151608] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [46.151765] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [46.151925] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: no [modeset]
<7> [46.152084] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [46.152258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [46.152424] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [46.152585] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [46.152744] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [46.152901] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [46.153057] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [46.153228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [46.153469] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Disable audio codec on [CRTC:108:pipe A]
<7> [46.169292] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [46.179143] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [46.179741] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.179929] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.180542] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.180737] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [46.180755] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [46.180975] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [46.186128] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [46.186420] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:108:pipe A]
<7> [46.186686] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [46.186931] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [46.187208] 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> [46.187539] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [46.187805] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [46.188025] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [46.188254] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [46.188472] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [46.188698] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [46.188925] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [46.189168] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [46.189392] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [46.189621] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [46.189847] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [46.190064] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [46.190299] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [46.190515] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [46.190737] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [46.190956] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [46.191188] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [46.191407] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [46.191635] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [46.191860] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [46.192071] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [46.192305] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [46.192797] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [46.193107] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [46.193350] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [46.193609] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [46.194517] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [46.194760] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [46.194965] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [46.195196] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [46.195242] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:342:HDMI-A-1]
<7> [46.195337] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:342:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [46.195589] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [46.195838] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [46.196069] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:341:DDI B/PHY B] [CRTC:108:pipe A]
<7> [46.196298] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [46.196534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [46.196770] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [46.196997] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [46.197266] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [46.197491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [46.197704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [46.197920] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [46.198154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [46.198374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [46.198609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [46.198829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [46.199050] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [46.199285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [46.199499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [46.199719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [46.199944] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [46.200176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [46.200400] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [46.200620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [46.200852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [46.201090] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [46.201417] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [46.201635] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [46.201859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [46.202081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [46.202311] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [46.202540] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [46.202757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [46.202977] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [46.203216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [46.203437] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [46.203656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [46.203888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [46.204121] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [46.204333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [46.204545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [46.204757] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [46.204980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [46.205227] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [46.205453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in avi infoframe
<7> [46.205682] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.205904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.206135] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.206138] i915 0000:00:02.0: colorspace: RGB
<7> [46.206141] i915 0000:00:02.0: scan mode: Underscan
<7> [46.206143] i915 0000:00:02.0: colorimetry: No Data
<7> [46.206145] i915 0000:00:02.0: picture aspect: No Data
<7> [46.206147] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.206150] i915 0000:00:02.0: itc: No Data
<7> [46.206152] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.206154] i915 0000:00:02.0: quantization range: Full
<7> [46.206157] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.206160] i915 0000:00:02.0: video code: 0
<7> [46.206162] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.206165] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.206167] i915 0000:00:02.0: pixel repeat: 0
<7> [46.206170] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.206173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in spd infoframe
<7> [46.206390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.206609] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.206833] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.206836] i915 0000:00:02.0: vendor: Intel
<7> [46.206838] i915 0000:00:02.0: product: Integrated gfx
<7> [46.206841] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.206844] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hdmi infoframe
<7> [46.207076] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [46.207307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [46.207522] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.207525] i915 0000:00:02.0: empty frame
<7> [46.207529] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [46.207798] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [46.208032] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [46.208336] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [46.208559] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [46.208783] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [46.208994] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [46.209238] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [46.209448] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [46.209691] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 1142000 num active planes 1
<7> [46.209922] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [46.210159] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [46.210415] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [46.210676] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [46.210935] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [46.211159] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [46.211384] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [46.211610] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:108:pipe A] allocated DPLL 0
<7> [46.211840] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:108:pipe A] reserving DPLL 0
<7> [46.212068] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: yes [modeset]
<7> [46.212313] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [46.212542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [46.212778] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [46.213007] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [46.213245] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [46.213460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [46.213672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [46.213891] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [46.214117] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [46.214119] i915 0000:00:02.0: colorspace: RGB
<7> [46.214122] i915 0000:00:02.0: scan mode: Underscan
<7> [46.214124] i915 0000:00:02.0: colorimetry: No Data
<7> [46.214127] i915 0000:00:02.0: picture aspect: No Data
<7> [46.214129] i915 0000:00:02.0: active aspect: Same as Picture
<7> [46.214131] i915 0000:00:02.0: itc: No Data
<7> [46.214133] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [46.214135] i915 0000:00:02.0: quantization range: Full
<7> [46.214138] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [46.214140] i915 0000:00:02.0: video code: 0
<7> [46.214142] i915 0000:00:02.0: ycc quantization range: Full
<7> [46.214144] i915 0000:00:02.0: hdmi content type: Graphics
<7> [46.214147] i915 0000:00:02.0: pixel repeat: 0
<7> [46.214149] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [46.214153] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [46.214155] i915 0000:00:02.0: vendor: Intel
<7> [46.214158] i915 0000:00:02.0: product: Integrated gfx
<7> [46.214161] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [46.214164] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [46.214166] i915 0000:00:02.0: empty frame
<7> [46.214169] 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> [46.214390] 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> [46.214620] 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> [46.214839] 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> [46.215052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [46.215296] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [46.215519] 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> [46.215736] 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> [46.215964] 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> [46.216210] 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> [46.216429] 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> [46.216645] 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> [46.216867] 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> [46.217088] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [46.217335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [46.217555] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [46.217767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [46.217991] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [46.218231] 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> [46.218457] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [46.218681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [46.218899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [46.219119] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.219333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.219550] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.219771] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [46.219993] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [46.220220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.220436] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.220653] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [46.220874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [46.221103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [46.221328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:390] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [46.221556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [46.221768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [46.221979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [46.222217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [46.222437] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [46.222656] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [46.222884] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [46.223116] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [46.223330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [46.224269] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [46.224514] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [46.224763] 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> [46.225076] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [46.225321] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [46.225556] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [46.225753] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [46.226034] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [46.226260] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [46.226414] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [46.226495] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [46.226745] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [46.226945] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [46.227185] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [46.227447] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [46.227662] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [46.227878] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [46.228075] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [46.228298] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [46.228521] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [46.228736] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [46.228942] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [46.229169] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [46.229384] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [46.229602] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [46.230056] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [46.230404] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:108:pipe A]
<7> [46.230628] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [46.230921] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [46.231265] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [46.231725] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [46.232360] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.232565] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [46.233212] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [46.233247] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [46.233171] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [46.233425] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [46.233447] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [46.244155] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [46.244424] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [46.250530] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Enable audio codec on [CRTC:108:pipe A], 64 bytes ELD
<7> [46.258846] 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> [46.259087] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [46.259344] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [46.259583] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [46.259587] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [46.259590] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [46.259593] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [46.259774] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [46.260166] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [46.313820] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [46.318148] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [46.318181] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [47.148338] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port G/TC#4: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [47.148420] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port E/TC#2: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [47.148411] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port F/TC#3: TC port mode reset (tbt-alt -> disconnected) pin assignment: - max lanes: 4
<7> [47.148436] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [47.149192] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [47.149859] i915 0000:00:02.0: [drm:intel_tc_port_reset_mode [i915]] Port D/TC#1: TC port mode reset (legacy -> disconnected) pin assignment: - max lanes: 4
<7> [57.235441] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [57.235684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [57.236279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [57.236767] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [57.237303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [57.237772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [57.238284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [57.238758] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [57.239252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [57.239714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [57.239931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [57.240091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [57.240246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [57.240401] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [57.240558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [57.240714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [57.240870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [57.241044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [57.241204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [57.241360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [57.241515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [57.241671] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [57.241827] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [57.241995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [57.242153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [57.242308] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [57.242463] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [57.242618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [57.242772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [57.242933] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [57.243089] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [57.243243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [57.243399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [57.243554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [57.243708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [57.243863] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [57.244029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [57.244188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [57.244343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [57.244498] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [57.244653] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [57.244808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in avi infoframe
<7> [57.244972] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.245132] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.245134] i915 0000:00:02.0: colorspace: RGB
<7> [57.245135] i915 0000:00:02.0: scan mode: Underscan
<7> [57.245137] i915 0000:00:02.0: colorimetry: No Data
<7> [57.245138] i915 0000:00:02.0: picture aspect: No Data
<7> [57.245140] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.245141] i915 0000:00:02.0: itc: No Data
<7> [57.245143] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.245144] i915 0000:00:02.0: quantization range: Full
<7> [57.245146] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.245147] i915 0000:00:02.0: video code: 0
<7> [57.245148] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.245150] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.245151] i915 0000:00:02.0: pixel repeat: 0
<7> [57.245153] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.245154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.245315] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in spd infoframe
<7> [57.245476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.245635] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.245637] i915 0000:00:02.0: vendor: Intel
<7> [57.245638] i915 0000:00:02.0: product: Integrated gfx
<7> [57.245640] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.245642] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.245800] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hdmi infoframe
<7> [57.245958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.246129] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.246133] i915 0000:00:02.0: empty frame
<7> [57.246135] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.246293] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [57.246450] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:108:pipe A] releasing DPLL 0
<7> [57.246635] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [57.246795] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [57.246996] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [57.247154] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [57.247310] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [57.247466] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [57.247620] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [57.247774] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [57.247947] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 0 num active planes 0
<7> [57.248122] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [57.248304] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [57.248481] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [57.248667] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [57.248827] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [57.248986] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [57.249164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: no [modeset]
<7> [57.249328] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [NOFB], visible: no
<7> [57.249487] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [57.249645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [57.249801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [57.249958] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [57.250128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [57.250289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [57.250443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [57.250679] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Disable audio codec on [CRTC:108:pipe A]
<7> [57.260468] i915 0000:00:02.0: [drm:intel_audio_component_get_eld [i915]] Not valid for port B
<7> [57.271061] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [57.271670] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.271831] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [57.272366] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.272588] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [57.272612] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [57.272851] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [57.277395] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [57.277653] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:108:pipe A]
<7> [57.277915] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [57.278157] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:33:plane 1A]
<7> [57.278412] 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> [57.278733] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [57.278987] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [57.279213] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [57.279463] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [57.279696] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [57.279911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [57.280125] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [57.280341] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [57.280574] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [57.280807] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [57.281040] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [57.281273] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [57.281510] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [57.281738] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [57.281967] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [57.282186] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [57.282400] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [57.282638] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [57.282852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [57.283068] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [57.283295] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [57.283529] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [57.284000] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [57.284301] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [57.284581] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [57.284845] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [57.285760] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:185:pipe B]
<7> [57.286008] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:262:pipe C]
<7> [57.286217] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:339:pipe D]
<7> [57.286448] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:108:pipe A]
<7> [57.286505] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:342:HDMI-A-1]
<7> [57.286606] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:342:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [57.286857] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [57.287080] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [57.287305] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:341:DDI B/PHY B] [CRTC:108:pipe A]
<7> [57.287537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [57.287766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [57.288007] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [57.288230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [57.288455] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [57.288696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [57.288921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [57.289147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [57.289381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [57.289617] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [57.289830] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [57.290052] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [57.290272] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [57.290489] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [57.290739] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [57.290959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [57.291187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [57.291403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [57.291636] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [57.291856] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [57.292078] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [57.292307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [57.292537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [57.292774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [57.292988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [57.293204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [57.293427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [57.293659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [57.293883] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [57.294100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [57.294325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [57.294548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [57.294787] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [57.295018] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [57.295251] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [57.295463] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [57.295691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [57.295902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [57.296130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [57.296355] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [57.296579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in avi infoframe
<7> [57.296825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.297044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.297269] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.297273] i915 0000:00:02.0: colorspace: RGB
<7> [57.297276] i915 0000:00:02.0: scan mode: Underscan
<7> [57.297279] i915 0000:00:02.0: colorimetry: No Data
<7> [57.297281] i915 0000:00:02.0: picture aspect: No Data
<7> [57.297284] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.297287] i915 0000:00:02.0: itc: No Data
<7> [57.297289] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.297292] i915 0000:00:02.0: quantization range: Full
<7> [57.297295] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.297298] i915 0000:00:02.0: video code: 0
<7> [57.297300] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.297303] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.297306] i915 0000:00:02.0: pixel repeat: 0
<7> [57.297309] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.297312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in spd infoframe
<7> [57.297539] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.297818] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.298030] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.298033] i915 0000:00:02.0: vendor: Intel
<7> [57.298035] i915 0000:00:02.0: product: Integrated gfx
<7> [57.298037] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.298039] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:108:pipe A] fastset requirement not met in hdmi infoframe
<7> [57.298253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [57.298470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [57.298706] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.298709] i915 0000:00:02.0: empty frame
<7> [57.298713] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:108:pipe A] fastset requirement not met, forcing full modeset
<7> [57.298975] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [57.299202] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:108:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [57.299486] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:33:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [57.299725] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:103:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [57.299947] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [57.300170] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [57.300388] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [57.300607] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:33: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> [57.300872] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:108:pipe A] data rate 1142000 num active planes 1
<7> [57.301101] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [57.301336] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [57.301595] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:108:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [57.301856] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [57.302110] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [57.302328] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [57.302550] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [57.302862] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:108:pipe A] allocated DPLL 0
<7> [57.303091] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:108:pipe A] reserving DPLL 0
<7> [57.303323] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:108:pipe A] enable: yes [modeset]
<7> [57.303552] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [57.303799] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [57.304034] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [57.304256] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [57.304468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [57.304681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [57.304921] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [57.305146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [57.305385] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [57.305388] i915 0000:00:02.0: colorspace: RGB
<7> [57.305391] i915 0000:00:02.0: scan mode: Underscan
<7> [57.305394] i915 0000:00:02.0: colorimetry: No Data
<7> [57.305396] i915 0000:00:02.0: picture aspect: No Data
<7> [57.305399] i915 0000:00:02.0: active aspect: Same as Picture
<7> [57.305402] i915 0000:00:02.0: itc: No Data
<7> [57.305404] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [57.305407] i915 0000:00:02.0: quantization range: Full
<7> [57.305409] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [57.305412] i915 0000:00:02.0: video code: 0
<7> [57.305415] i915 0000:00:02.0: ycc quantization range: Full
<7> [57.305417] i915 0000:00:02.0: hdmi content type: Graphics
<7> [57.305420] i915 0000:00:02.0: pixel repeat: 0
<7> [57.305422] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [57.305426] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [57.305429] i915 0000:00:02.0: vendor: Intel
<7> [57.305431] i915 0000:00:02.0: product: Integrated gfx
<7> [57.305434] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [57.305437] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [57.305440] i915 0000:00:02.0: empty frame
<7> [57.305443] 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> [57.305663] 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> [57.305894] 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> [57.306115] 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> [57.306335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [57.306557] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [57.306785] 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> [57.307016] 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> [57.307250] 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> [57.307466] 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> [57.307680] 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> [57.307924] 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> [57.308143] 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> [57.308355] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 285500, pipe src: 1920x1080+0+0, pixel rate 285500, min cdclk 192000
<7> [57.308598] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [57.308817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [57.309040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [57.309258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [57.309480] 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> [57.309709] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [57.309955] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [57.310169] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [57.310378] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.310594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.310816] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.311035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [57.311263] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [57.311479] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.311694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.311926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [57.312145] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [57.312373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [57.312599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:33:plane 1A] fb: [FB:390] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [57.312825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [57.313036] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [57.313254] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:plane 2A] fb: [NOFB], visible: no
<7> [57.313470] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:53:plane 3A] fb: [NOFB], visible: no
<7> [57.313691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:63:plane 4A] fb: [NOFB], visible: no
<7> [57.313926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:73:plane 5A] fb: [NOFB], visible: no
<7> [57.314137] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:83:plane 6A] fb: [NOFB], visible: no
<7> [57.314356] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 7A] fb: [NOFB], visible: no
<7> [57.314579] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:103:cursor A] fb: [NOFB], visible: no
<7> [57.315025] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [57.315274] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [57.315524] 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> [57.316274] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:341:DDI B/PHY B]
<7> [57.316507] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:351:DDI TC1/PHY TC1]
<7> [57.316733] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:353:DP-MST A]
<7> [57.316939] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:354:DP-MST B]
<7> [57.317130] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [57.317181] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:355:DP-MST C]
<7> [57.317422] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:356:DP-MST D]
<7> [57.317694] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:361:DDI TC2/PHY TC2]
<7> [57.317896] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:363:DP-MST A]
<7> [57.318103] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:364:DP-MST B]
<7> [57.318333] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:365:DP-MST C]
<7> [57.318601] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:366:DP-MST D]
<7> [57.318813] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:370:DDI TC3/PHY TC3]
<7> [57.319039] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:372:DP-MST A]
<7> [57.319255] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:373:DP-MST B]
<7> [57.319448] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:374:DP-MST C]
<7> [57.319663] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:375:DP-MST D]
<7> [57.319884] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:379:DDI TC4/PHY TC4]
<7> [57.320112] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:381:DP-MST A]
<7> [57.320319] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:382:DP-MST B]
<7> [57.320533] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:383:DP-MST C]
<7> [57.320747] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:384:DP-MST D]
<7> [57.321187] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [57.321525] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:108:pipe A]
<7> [57.321752] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [57.321994] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [57.322030] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<7> [57.322098] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [57.322416] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [57.322884] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:342:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [57.323503] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.323723] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [57.324333] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [57.324558] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:342:HDMI-A-1] Failed to read TMDS config: -6
<7> [57.324581] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:342:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [57.335045] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [57.335341] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:33:plane 1A]
<7> [57.341611] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:342:HDMI-A-1][ENCODER:341:DDI B/PHY B] Enable audio codec on [CRTC:108:pipe A], 64 bytes ELD
<7> [57.349962] 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> [57.350189] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [57.350436] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<6> [57.350679] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 5 not expected
<6> [57.350683] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 0 not expected
<6> [57.350686] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 10 not expected
<6> [57.350689] snd_hda_codec_intelhdmi hdaudioC0D2: HDMI: audio coding xtype 6 not expected
<7> [57.350863] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:342:HDMI-A-1]
<7> [57.351252] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:108:pipe A]
<7> [57.406806] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x810
<7> [57.411113] azx_init_pci: snd_hda_intel 0000:00:1f.3: Clearing TCSEL
<7> [57.411152] azx_init_pci: snd_hda_intel 0000:00:1f.3: SCH snoop: Enabled
<6> [65.277040] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2025-11-14 06:35:26