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

Result: Fail

git-log-oneline i915_display_info23 igt_runner23 runtimes23 results23.json results23-i915-load.json guc_logs23.tar i915_display_info_post_exec23 boot23 dmesg23

DetailValue
Duration 19.16 seconds
Hostname 
shard-tglu-10
Igt-Version 
IGT-Version: 2.3-g063e64ae4 (x86_64) (Linux: 7.0.0-rc1-CI_DRM_18022-g45a3045fc0dc+ x86_64)
Out 
Using IGT_SRANDOM=1771891049 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 8045.7us +- 3.420us accuracy 0.13%
vblank interval differs from modeline! expected 8334.6us, measured 8046us +- 3.420us, difference 288.9us (84.5 sigma)
Event flip: expected 941, counted 930, passrate = 25.81%, encoder type 2
Event vblank: expected 941, counted 930, passrate = 24.73%, encoder type 2
Expected frametime: 8335us; measured 8255.1us +- 2.630us accuracy 0.10%
vblank interval differs from modeline! expected 8334.6us, measured 8255us +- 2.630us, difference 79.4us (30.2 sigma)
Event flip: expected 913, counted 910, passrate = 68.13%, encoder type 2
Event vblank: expected 913, counted 910, passrate = 68.13%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1887 run_test()
  #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
  #4 ../tests/kms_flip.c:2043 main()
  #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (19.160s)
Err 
Starting subtest: flip-vs-absolute-wf_vblank
Starting dynamic subtest: A-HDMI-A1
(kms_flip:6503) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:6503) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.105377
last_received_ts = 228.105164
last_seq = 16787
current_ts = 228.188248
current_received_ts = 228.188080
current_seq = 16797
count = 45
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.105377/16787, current 228.188248/16797: elapsed=82870.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.113678
last_received_ts = 228.113480
last_seq = 16788
current_ts = 228.196533
current_received_ts = 228.196335
current_seq = 16798
count = 46
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.113678/16788, current 228.196533/16798: elapsed=82861.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.188248
last_received_ts = 228.188080
last_seq = 16797
current_ts = 228.271118
current_received_ts = 228.270889
current_seq = 16807
count = 46
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.188248/16797, current 228.271118/16807: elapsed=82864.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.196533
last_received_ts = 228.196335
last_seq = 16798
current_ts = 228.279404
current_received_ts = 228.279160
current_seq = 16808
count = 47
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.196533/16798, current 228.279404/16808: elapsed=82866.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.271118
last_received_ts = 228.270889
last_seq = 16807
current_ts = 228.353973
current_received_ts = 228.353760
current_seq = 16817
count = 47
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.271118/16807, current 228.353973/16817: elapsed=82866.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.279404
last_received_ts = 228.279160
last_seq = 16808
current_ts = 228.362274
current_received_ts = 228.361938
current_seq = 16818
count = 48
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.279404/16808, current 228.362274/16818: elapsed=82867.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.353973
last_received_ts = 228.353760
last_seq = 16817
current_ts = 228.436874
current_received_ts = 228.436646
current_seq = 16827
count = 48
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.353973/16817, current 228.436874/16827: elapsed=82886.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.362274
last_received_ts = 228.361938
last_seq = 16818
current_ts = 228.445160
current_received_ts = 228.444901
current_seq = 16828
count = 49
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.362274/16818, current 228.445160/16828: elapsed=82890.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.436874
last_received_ts = 228.436646
last_seq = 16827
current_ts = 228.519791
current_received_ts = 228.519577
current_seq = 16837
count = 49
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.436874/16827, current 228.519791/16837: elapsed=82920.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.445160
last_received_ts = 228.444901
last_seq = 16828
current_ts = 228.528076
current_received_ts = 228.527893
current_seq = 16838
count = 50
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.445160/16828, current 228.528076/16838: elapsed=82922.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.519791
last_received_ts = 228.519577
last_seq = 16837
current_ts = 228.602707
current_received_ts = 228.602493
current_seq = 16847
count = 50
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.519791/16837, current 228.602707/16847: elapsed=82918.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.528076
last_received_ts = 228.527893
last_seq = 16838
current_ts = 228.610992
current_received_ts = 228.610748
current_seq = 16848
count = 51
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.528076/16838, current 228.610992/16848: elapsed=82919.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.602707
last_received_ts = 228.602493
last_seq = 16847
current_ts = 228.685623
current_received_ts = 228.685410
current_seq = 16857
count = 51
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.602707/16847, current 228.685623/16857: elapsed=82921.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.610992
last_received_ts = 228.610748
last_seq = 16848
current_ts = 228.693924
current_received_ts = 228.693649
current_seq = 16858
count = 52
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.610992/16848, current 228.693924/16858: elapsed=82924.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.685623
last_received_ts = 228.685410
last_seq = 16857
current_ts = 228.768539
current_received_ts = 228.768326
current_seq = 16867
count = 52
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.685623/16857, current 228.768539/16867: elapsed=82921.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.693924
last_received_ts = 228.693649
last_seq = 16858
current_ts = 228.776840
current_received_ts = 228.776566
current_seq = 16868
count = 53
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.693924/16858, current 228.776840/16868: elapsed=82919.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.768539
last_received_ts = 228.768326
last_seq = 16867
current_ts = 228.851471
current_received_ts = 228.851242
current_seq = 16877
count = 53
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.768539/16867, current 228.851471/16877: elapsed=82921.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.776840
last_received_ts = 228.776566
last_seq = 16868
current_ts = 228.859756
current_received_ts = 228.859543
current_seq = 16878
count = 54
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.776840/16868, current 228.859756/16878: elapsed=82919.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.851471
last_received_ts = 228.851242
last_seq = 16877
current_ts = 228.934387
current_received_ts = 228.934174
current_seq = 16887
count = 54
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.851471/16877, current 228.934387/16887: elapsed=82922.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.859756
last_received_ts = 228.859543
last_seq = 16878
current_ts = 228.942688
current_received_ts = 228.942429
current_seq = 16888
count = 55
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.859756/16878, current 228.942688/16888: elapsed=82921.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 228.934387
last_received_ts = 228.934174
last_seq = 16887
current_ts = 229.017303
current_received_ts = 229.017120
current_seq = 16897
count = 55
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 228.934387/16887, current 229.017303/16897: elapsed=82916.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 228.942688
last_received_ts = 228.942429
last_seq = 16888
current_ts = 229.025604
current_received_ts = 229.025391
current_seq = 16898
count = 56
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 228.942688/16888, current 229.025604/16898: elapsed=82918.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.017303
last_received_ts = 229.017120
last_seq = 16897
current_ts = 229.100235
current_received_ts = 229.100067
current_seq = 16907
count = 56
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.017303/16897, current 229.100235/16907: elapsed=82924.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.025604
last_received_ts = 229.025391
last_seq = 16898
current_ts = 229.108521
current_received_ts = 229.108170
current_seq = 16908
count = 57
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.025604/16898, current 229.108521/16908: elapsed=82922.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.100235
last_received_ts = 229.100067
last_seq = 16907
current_ts = 229.183151
current_received_ts = 229.182922
current_seq = 16917
count = 57
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.100235/16907, current 229.183151/16917: elapsed=82920.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.108521
last_received_ts = 229.108170
last_seq = 16908
current_ts = 229.191437
current_received_ts = 229.191162
current_seq = 16918
count = 58
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.108521/16908, current 229.191437/16918: elapsed=82917.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.183151
last_received_ts = 229.182922
last_seq = 16917
current_ts = 229.266068
current_received_ts = 229.265839
current_seq = 16927
count = 58
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.183151/16917, current 229.266068/16927: elapsed=82920.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.191437
last_received_ts = 229.191162
last_seq = 16918
current_ts = 229.274353
current_received_ts = 229.274155
current_seq = 16928
count = 59
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.191437/16918, current 229.274353/16928: elapsed=82918.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.266068
last_received_ts = 229.265839
last_seq = 16927
current_ts = 229.348984
current_received_ts = 229.348770
current_seq = 16937
count = 59
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.266068/16927, current 229.348984/16937: elapsed=82918.0us expected=82551.3us +- 412.8us, error 0.4%
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.274353
last_received_ts = 229.274155
last_seq = 16928
current_ts = 229.357285
current_received_ts = 229.356964
current_seq = 16938
count = 60
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.274353/16928, current 229.357285/16938: elapsed=82923.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.348984
last_received_ts = 229.348770
last_seq = 16937
current_ts = 229.431915
current_received_ts = 229.431702
current_seq = 16947
count = 60
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.348984/16937, current 229.431915/16947: elapsed=82930.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.357285
last_received_ts = 229.356964
last_seq = 16938
current_ts = 229.440216
current_received_ts = 229.439911
current_seq = 16948
count = 61
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.357285/16938, current 229.440216/16948: elapsed=82942.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.431915
last_received_ts = 229.431702
last_seq = 16947
current_ts = 229.514893
current_received_ts = 229.514694
current_seq = 16957
count = 61
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.431915/16947, current 229.514893/16957: elapsed=82972.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.431915/16947, current 229.514893/16957: elapsed=82972.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.440216
last_received_ts = 229.439911
last_seq = 16948
current_ts = 229.523193
current_received_ts = 229.522934
current_seq = 16958
count = 62
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.440216/16948, current 229.523193/16958: elapsed=82965.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.440216/16948, current 229.523193/16958: elapsed=82965.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.514893
last_received_ts = 229.514694
last_seq = 16957
current_ts = 229.597855
current_received_ts = 229.597641
current_seq = 16967
count = 62
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.514893/16957, current 229.597855/16967: elapsed=82968.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.514893/16957, current 229.597855/16967: elapsed=82968.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.523193
last_received_ts = 229.522934
last_seq = 16958
current_ts = 229.606155
current_received_ts = 229.605942
current_seq = 16968
count = 63
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.523193/16958, current 229.606155/16968: elapsed=82966.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.523193/16958, current 229.606155/16968: elapsed=82966.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.597855
last_received_ts = 229.597641
last_seq = 16967
current_ts = 229.680832
current_received_ts = 229.680511
current_seq = 16977
count = 63
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.597855/16967, current 229.680832/16977: elapsed=82968.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.597855/16967, current 229.680832/16977: elapsed=82968.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.606155
last_received_ts = 229.605942
last_seq = 16968
current_ts = 229.689117
current_received_ts = 229.688828
current_seq = 16978
count = 64
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.606155/16968, current 229.689117/16978: elapsed=82972.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.606155/16968, current 229.689117/16978: elapsed=82972.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.680832
last_received_ts = 229.680511
last_seq = 16977
current_ts = 229.763794
current_received_ts = 229.763580
current_seq = 16987
count = 64
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.680832/16977, current 229.763794/16987: elapsed=82970.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.680832/16977, current 229.763794/16987: elapsed=82970.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.689117
last_received_ts = 229.688828
last_seq = 16978
current_ts = 229.772095
current_received_ts = 229.771912
current_seq = 16988
count = 65
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.689117/16978, current 229.772095/16988: elapsed=82966.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.689117/16978, current 229.772095/16988: elapsed=82966.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.763794
last_received_ts = 229.763580
last_seq = 16987
current_ts = 229.846756
current_received_ts = 229.846542
current_seq = 16997
count = 65
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.763794/16987, current 229.846756/16997: elapsed=82967.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.763794/16987, current 229.846756/16997: elapsed=82967.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.772095
last_received_ts = 229.771912
last_seq = 16988
current_ts = 229.855057
current_received_ts = 229.854797
current_seq = 16998
count = 66
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.772095/16988, current 229.855057/16998: elapsed=82967.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.772095/16988, current 229.855057/16998: elapsed=82967.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.846756
last_received_ts = 229.846542
last_seq = 16997
current_ts = 229.929733
current_received_ts = 229.929520
current_seq = 17007
count = 66
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.846756/16997, current 229.929733/17007: elapsed=82966.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.846756/16997, current 229.929733/17007: elapsed=82966.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.855057
last_received_ts = 229.854797
last_seq = 16998
current_ts = 229.938019
current_received_ts = 229.937759
current_seq = 17008
count = 67
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.855057/16998, current 229.938019/17008: elapsed=82969.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.855057/16998, current 229.938019/17008: elapsed=82969.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 229.929733
last_received_ts = 229.929520
last_seq = 17007
current_ts = 230.012695
current_received_ts = 230.012466
current_seq = 17017
count = 67
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 229.929733/17007, current 230.012695/17017: elapsed=82967.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 229.929733/17007, current 230.012695/17017: elapsed=82967.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 229.938019
last_received_ts = 229.937759
last_seq = 17008
current_ts = 230.020996
current_received_ts = 230.020767
current_seq = 17018
count = 68
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 229.938019/17008, current 230.020996/17018: elapsed=82970.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 229.938019/17008, current 230.020996/17018: elapsed=82970.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.012695
last_received_ts = 230.012466
last_seq = 17017
current_ts = 230.095673
current_received_ts = 230.095444
current_seq = 17027
count = 68
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.012695/17017, current 230.095673/17027: elapsed=82970.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.012695/17017, current 230.095673/17027: elapsed=82970.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.020996
last_received_ts = 230.020767
last_seq = 17018
current_ts = 230.103958
current_received_ts = 230.103729
current_seq = 17028
count = 69
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.020996/17018, current 230.103958/17028: elapsed=82969.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.020996/17018, current 230.103958/17028: elapsed=82969.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.095673
last_received_ts = 230.095444
last_seq = 17027
current_ts = 230.178635
current_received_ts = 230.178406
current_seq = 17037
count = 69
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.095673/17027, current 230.178635/17037: elapsed=82970.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.095673/17027, current 230.178635/17037: elapsed=82970.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.103958
last_received_ts = 230.103729
last_seq = 17028
current_ts = 230.186935
current_received_ts = 230.186676
current_seq = 17038
count = 70
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.103958/17028, current 230.186935/17038: elapsed=82968.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.103958/17028, current 230.186935/17038: elapsed=82968.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.178635
last_received_ts = 230.178406
last_seq = 17037
current_ts = 230.261597
current_received_ts = 230.261368
current_seq = 17047
count = 70
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.178635/17037, current 230.261597/17047: elapsed=82968.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.178635/17037, current 230.261597/17047: elapsed=82968.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.186935
last_received_ts = 230.186676
last_seq = 17038
current_ts = 230.269897
current_received_ts = 230.269623
current_seq = 17048
count = 71
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.186935/17038, current 230.269897/17048: elapsed=82967.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.186935/17038, current 230.269897/17048: elapsed=82967.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.261597
last_received_ts = 230.261368
last_seq = 17047
current_ts = 230.344574
current_received_ts = 230.344269
current_seq = 17057
count = 71
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.261597/17047, current 230.344574/17057: elapsed=82964.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.269897
last_received_ts = 230.269623
last_seq = 17048
current_ts = 230.352875
current_received_ts = 230.352646
current_seq = 17058
count = 72
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.269897/17048, current 230.352875/17058: elapsed=82968.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.269897/17048, current 230.352875/17058: elapsed=82968.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.344574
last_received_ts = 230.344269
last_seq = 17057
current_ts = 230.427551
current_received_ts = 230.427338
current_seq = 17067
count = 72
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.344574/17057, current 230.427551/17067: elapsed=82977.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.344574/17057, current 230.427551/17067: elapsed=82977.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.352875
last_received_ts = 230.352646
last_seq = 17058
current_ts = 230.435852
current_received_ts = 230.435577
current_seq = 17068
count = 73
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.352875/17058, current 230.435852/17068: elapsed=82980.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.352875/17058, current 230.435852/17068: elapsed=82980.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.427551
last_received_ts = 230.427338
last_seq = 17067
current_ts = 230.510559
current_received_ts = 230.510345
current_seq = 17077
count = 73
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.427551/17067, current 230.510559/17077: elapsed=83014.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.427551/17067, current 230.510559/17077: elapsed=83014.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.435852
last_received_ts = 230.435577
last_seq = 17068
current_ts = 230.518860
current_received_ts = 230.518570
current_seq = 17078
count = 74
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.435852/17068, current 230.518860/17078: elapsed=83007.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.435852/17068, current 230.518860/17078: elapsed=83007.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.510559
last_received_ts = 230.510345
last_seq = 17077
current_ts = 230.593567
current_received_ts = 230.593353
current_seq = 17087
count = 74
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.510559/17077, current 230.593567/17087: elapsed=83007.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.510559/17077, current 230.593567/17087: elapsed=83007.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.518860
last_received_ts = 230.518570
last_seq = 17078
current_ts = 230.601868
current_received_ts = 230.601624
current_seq = 17088
count = 75
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.518860/17078, current 230.601868/17088: elapsed=83015.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.518860/17078, current 230.601868/17088: elapsed=83015.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.593567
last_received_ts = 230.593353
last_seq = 17087
current_ts = 230.676575
current_received_ts = 230.676346
current_seq = 17097
count = 75
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.593567/17087, current 230.676575/17097: elapsed=83011.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.593567/17087, current 230.676575/17097: elapsed=83011.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.601868
last_received_ts = 230.601624
last_seq = 17088
current_ts = 230.684875
current_received_ts = 230.684540
current_seq = 17098
count = 76
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.601868/17088, current 230.684875/17098: elapsed=83006.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.601868/17088, current 230.684875/17098: elapsed=83006.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.676575
last_received_ts = 230.676346
last_seq = 17097
current_ts = 230.759583
current_received_ts = 230.759369
current_seq = 17107
count = 76
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.676575/17097, current 230.759583/17107: elapsed=83009.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.676575/17097, current 230.759583/17107: elapsed=83009.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.684875
last_received_ts = 230.684540
last_seq = 17098
current_ts = 230.767883
current_received_ts = 230.767609
current_seq = 17108
count = 77
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.684875/17098, current 230.767883/17108: elapsed=83012.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.684875/17098, current 230.767883/17108: elapsed=83012.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.759583
last_received_ts = 230.759369
last_seq = 17107
current_ts = 230.842606
current_received_ts = 230.842377
current_seq = 17117
count = 77
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.759583/17107, current 230.842606/17117: elapsed=83013.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.759583/17107, current 230.842606/17117: elapsed=83013.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.767883
last_received_ts = 230.767609
last_seq = 17108
current_ts = 230.850906
current_received_ts = 230.850677
current_seq = 17118
count = 78
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.767883/17108, current 230.850906/17118: elapsed=83011.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.767883/17108, current 230.850906/17118: elapsed=83011.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.842606
last_received_ts = 230.842377
last_seq = 17117
current_ts = 230.925613
current_received_ts = 230.925385
current_seq = 17127
count = 78
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.842606/17117, current 230.925613/17127: elapsed=83009.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.842606/17117, current 230.925613/17127: elapsed=83009.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.850906
last_received_ts = 230.850677
last_seq = 17118
current_ts = 230.933899
current_received_ts = 230.933685
current_seq = 17128
count = 79
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.850906/17118, current 230.933899/17128: elapsed=83007.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.850906/17118, current 230.933899/17128: elapsed=83007.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 230.925613
last_received_ts = 230.925385
last_seq = 17127
current_ts = 231.008606
current_received_ts = 231.008392
current_seq = 17137
count = 79
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 230.925613/17127, current 231.008606/17137: elapsed=83005.0us expected=82551.3us +- 412.8us, error 0.5%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 230.925613/17127, current 231.008606/17137: elapsed=83005.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 230.933899
last_received_ts = 230.933685
last_seq = 17128
current_ts = 231.016907
current_received_ts = 231.016632
current_seq = 17138
count = 80
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 230.933899/17128, current 231.016907/17138: elapsed=83008.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 230.933899/17128, current 231.016907/17138: elapsed=83008.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.008606
last_received_ts = 231.008392
last_seq = 17137
current_ts = 231.091629
current_received_ts = 231.091400
current_seq = 17147
count = 80
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.008606/17137, current 231.091629/17147: elapsed=83014.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.008606/17137, current 231.091629/17147: elapsed=83014.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.016907
last_received_ts = 231.016632
last_seq = 17138
current_ts = 231.099930
current_received_ts = 231.099716
current_seq = 17148
count = 81
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.016907/17138, current 231.099930/17148: elapsed=83010.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.016907/17138, current 231.099930/17148: elapsed=83010.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.091629
last_received_ts = 231.091400
last_seq = 17147
current_ts = 231.174637
current_received_ts = 231.174423
current_seq = 17157
count = 81
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.091629/17147, current 231.174637/17157: elapsed=83008.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.091629/17147, current 231.174637/17157: elapsed=83008.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.099930
last_received_ts = 231.099716
last_seq = 17148
current_ts = 231.182938
current_received_ts = 231.182678
current_seq = 17158
count = 82
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.099930/17148, current 231.182938/17158: elapsed=83012.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.099930/17148, current 231.182938/17158: elapsed=83012.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.174637
last_received_ts = 231.174423
last_seq = 17157
current_ts = 231.257645
current_received_ts = 231.257431
current_seq = 17167
count = 82
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.174637/17157, current 231.257645/17167: elapsed=83014.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.174637/17157, current 231.257645/17167: elapsed=83014.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.182938
last_received_ts = 231.182678
last_seq = 17158
current_ts = 231.265945
current_received_ts = 231.265686
current_seq = 17168
count = 83
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.182938/17158, current 231.265945/17168: elapsed=83010.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.182938/17158, current 231.265945/17168: elapsed=83010.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.257645
last_received_ts = 231.257431
last_seq = 17167
current_ts = 231.340652
current_received_ts = 231.340424
current_seq = 17177
count = 83
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.257645/17167, current 231.340652/17177: elapsed=83010.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.257645/17167, current 231.340652/17177: elapsed=83010.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.265945
last_received_ts = 231.265686
last_seq = 17168
current_ts = 231.348953
current_received_ts = 231.348694
current_seq = 17178
count = 84
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.265945/17168, current 231.348953/17178: elapsed=83010.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.265945/17168, current 231.348953/17178: elapsed=83010.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.340652
last_received_ts = 231.340424
last_seq = 17177
current_ts = 231.423676
current_received_ts = 231.423447
current_seq = 17187
count = 84
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.340652/17177, current 231.423676/17187: elapsed=83017.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.340652/17177, current 231.423676/17187: elapsed=83017.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.348953
last_received_ts = 231.348694
last_seq = 17178
current_ts = 231.431976
current_received_ts = 231.431717
current_seq = 17188
count = 85
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.348953/17178, current 231.431976/17188: elapsed=83021.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.348953/17178, current 231.431976/17188: elapsed=83021.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.423676
last_received_ts = 231.423447
last_seq = 17187
current_ts = 231.506729
current_received_ts = 231.506500
current_seq = 17197
count = 85
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.423676/17187, current 231.506729/17197: elapsed=83046.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.423676/17187, current 231.506729/17197: elapsed=83046.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.431976
last_received_ts = 231.431717
last_seq = 17188
current_ts = 231.515030
current_received_ts = 231.514725
current_seq = 17198
count = 86
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.431976/17188, current 231.515030/17198: elapsed=83049.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.431976/17188, current 231.515030/17198: elapsed=83049.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.506729
last_received_ts = 231.506500
last_seq = 17197
current_ts = 231.589767
current_received_ts = 231.589554
current_seq = 17207
count = 86
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.506729/17197, current 231.589767/17207: elapsed=83042.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.506729/17197, current 231.589767/17207: elapsed=83042.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.515030
last_received_ts = 231.514725
last_seq = 17198
current_ts = 231.598068
current_received_ts = 231.597763
current_seq = 17208
count = 87
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.515030/17198, current 231.598068/17208: elapsed=83047.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.515030/17198, current 231.598068/17208: elapsed=83047.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.589767
last_received_ts = 231.589554
last_seq = 17207
current_ts = 231.672821
current_received_ts = 231.672592
current_seq = 17217
count = 87
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.589767/17207, current 231.672821/17217: elapsed=83053.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.589767/17207, current 231.672821/17217: elapsed=83053.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.598068
last_received_ts = 231.597763
last_seq = 17208
current_ts = 231.681122
current_received_ts = 231.680801
current_seq = 17218
count = 88
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.598068/17208, current 231.681122/17218: elapsed=83047.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.598068/17208, current 231.681122/17218: elapsed=83047.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.672821
last_received_ts = 231.672592
last_seq = 17217
current_ts = 231.755859
current_received_ts = 231.755630
current_seq = 17227
count = 88
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.672821/17217, current 231.755859/17227: elapsed=83046.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.672821/17217, current 231.755859/17227: elapsed=83046.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.681122
last_received_ts = 231.680801
last_seq = 17218
current_ts = 231.764160
current_received_ts = 231.763855
current_seq = 17228
count = 89
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.681122/17218, current 231.764160/17228: elapsed=83045.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.681122/17218, current 231.764160/17228: elapsed=83045.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.755859
last_received_ts = 231.755630
last_seq = 17227
current_ts = 231.838913
current_received_ts = 231.838684
current_seq = 17237
count = 89
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.755859/17227, current 231.838913/17237: elapsed=83043.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.755859/17227, current 231.838913/17237: elapsed=83043.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = flip
last_ts = 231.764160
last_received_ts = 231.763855
last_seq = 17228
current_ts = 231.847214
current_received_ts = 231.846909
current_seq = 17238
count = 90
seq_step = 1
(kms_flip:6503) DEBUG: flip ts/seq: last 231.764160/17228, current 231.847214/17238: elapsed=83049.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent flip ts/seq: last 231.764160/17228, current 231.847214/17238: elapsed=83049.0us expected=82551.3us
(kms_flip:6503) DEBUG: name = vblank
last_ts = 231.838913
last_received_ts = 231.838684
last_seq = 17237
current_ts = 231.921951
current_received_ts = 231.921738
current_seq = 17247
count = 90
seq_step = 10
(kms_flip:6503) DEBUG: vblank ts/seq: last 231.838913/17237, current 231.921951/17247: elapsed=83051.0us expected=82551.3us +- 412.8us, error 0.6%
(kms_flip:6503) DEBUG: inconsistent vblank ts/seq: last 231.838913/17237, current 231.921951/17247: elapsed=83051.0us expected=82551.3us
(kms_flip:6503) INFO: Event flip: expected 913, counted 910, passrate = 68.13%, encoder type 2
(kms_flip:6503) DEBUG: dropped frames, expected 913, counted 910, passrate = 68.13%, encoder type 2
(kms_flip:6503) INFO: Event vblank: expected 913, counted 910, passrate = 68.13%, encoder type 2
(kms_flip:6503) DEBUG: dropped frames, expected 913, counted 910, passrate = 68.13%, encoder type 2
(kms_flip:6503) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1707:
(kms_flip:6503) CRITICAL: Failed assertion: !retried
(kms_flip:6503) igt_core-INFO: Stack trace:
(kms_flip:6503) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6503) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:6503) igt_core-INFO:   #2 ../tests/kms_flip.c:1887 run_test()
(kms_flip:6503) igt_core-INFO:   #3 ../tests/kms_flip.c:2120 __igt_unique____real_main2043()
(kms_flip:6503) igt_core-INFO:   #4 ../tests/kms_flip.c:2043 main()
(kms_flip:6503) igt_core-INFO:   #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
(kms_flip:6503) igt_core-INFO:   #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
(kms_flip:6503) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (19.160s)
Dmesg
<6> [228.591444] Console: switching to colour dummy device 80x25
<6> [228.591905] [IGT] kms_flip: executing
<7> [228.594380] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [228.595067] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [228.595346] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [228.604972] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [228.604981] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [228.609721] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [228.610128] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [228.610134] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [228.610589] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [228.610963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [228.610968] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [228.611354] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [228.611746] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [228.611753] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [228.612087] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<7> [228.612734] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1]
<7> [228.612747] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [228.640005] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [228.640016] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: DVI dual 0, max TMDS clock 300000 kHz
<7> [228.640019] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] No deep color support on this HDMI sink.
<7> [228.640021] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HF-VSDB: max TMDS clock: 600000 KHz, HDMI 2.1 support: no, DSC 1.2 support: no
<7> [228.640024] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [228.640027] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD monitor fitHeadlessGS
<7> [228.640030] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] HDMI: latency present 0 0, video latency 0 0, audio latency 0 0
<7> [228.640033] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:587:HDMI-A-1] ELD size 64, SAD count 10
<7> [228.640508] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [228.640666] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [228.641286] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0040 w(1)
<7> [228.641447] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [228.642342] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:HDMI-A-1] probed modes:
<7> [228.642348] 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> [228.642352] 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> [228.642356] 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> [228.642360] 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> [228.642364] 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> [228.642368] 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> [228.642372] 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> [228.642375] 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> [228.642379] 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> [228.642383] 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> [228.642387] 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> [228.642390] 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> [228.642394] 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> [228.642398] 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> [228.642402] 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> [228.642405] 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> [228.642409] 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> [228.642413] 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> [228.642417] 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> [228.642421] 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> [228.642424] 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> [228.642428] 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> [228.642432] 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> [228.642435] 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> [228.642439] 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> [228.642443] 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> [228.642447] 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> [228.642451] 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> [228.642455] 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> [228.642458] 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> [228.642462] 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> [228.642466] 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> [228.642470] 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> [228.642473] 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> [228.642477] 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> [228.642481] 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> [228.642484] 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> [228.642488] 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> [228.642492] 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> [228.642495] 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> [228.642499] 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> [228.642502] 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> [228.642506] 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> [228.642510] 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> [228.642513] 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> [228.642517] 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> [228.642521] 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> [228.642524] 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> [228.642528] 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> [228.642531] 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> [228.642535] 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> [228.642539] 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> [228.642542] 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> [228.642546] 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> [228.642549] 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> [228.642553] 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> [228.642557] 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> [228.642560] 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> [228.642564] 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> [228.642567] 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> [228.642571] 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> [228.642574] 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> [228.642578] 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> [228.642582] 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> [228.642585] 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> [228.642589] 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> [228.642592] 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> [228.642596] 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> [228.642599] 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> [228.642603] 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> [228.642607] 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> [228.642610] 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> [228.642614] 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> [228.642658] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1]
<7> [228.642662] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:597:DP-1]
<7> [228.647257] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:597:DP-1] disconnected
<7> [228.647277] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2]
<7> [228.647282] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:607:DP-2]
<7> [228.647600] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:DP-2] disconnected
<7> [228.647614] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3]
<7> [228.647618] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:616:DP-3]
<7> [228.647963] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:616:DP-3] disconnected
<7> [228.647978] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4]
<7> [228.647982] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:625:DP-4]
<7> [228.648304] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:625:DP-4] disconnected
<6> [228.649201] [IGT] kms_flip: starting subtest flip-vs-absolute-wf_vblank
<6> [228.652354] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [228.652798] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:634]
<7> [228.652909] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:637]
<7> [228.700267] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [228.700426] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [228.700683] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [228.700915] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [228.701176] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [228.701427] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [228.701651] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [228.701873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [228.702138] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [228.702399] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [228.702632] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [228.702852] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [228.703043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [228.703264] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [228.703458] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [228.703669] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [228.703867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [228.704061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [228.704273] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [228.704465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [228.704656] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [228.704849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [228.705040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [228.705253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [228.705446] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [228.705613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [228.705769] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [228.705926] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [228.706081] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [228.706260] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [228.706416] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [228.706572] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [228.706728] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [228.706885] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [228.707040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [228.707212] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [228.707393] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [228.707550] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [228.707706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [228.707861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [228.708017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [228.708173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [228.708341] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.708498] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [228.708500] i915 0000:00:02.0: colorspace: RGB
<7> [228.708502] i915 0000:00:02.0: scan mode: Underscan
<7> [228.708504] i915 0000:00:02.0: colorimetry: No Data
<7> [228.708505] i915 0000:00:02.0: picture aspect: No Data
<7> [228.708507] i915 0000:00:02.0: active aspect: Same as Picture
<7> [228.708508] i915 0000:00:02.0: itc: No Data
<7> [228.708510] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [228.708511] i915 0000:00:02.0: quantization range: Full
<7> [228.708512] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [228.708514] i915 0000:00:02.0: video code: 0
<7> [228.708515] i915 0000:00:02.0: ycc quantization range: Full
<7> [228.708517] i915 0000:00:02.0: hdmi content type: Graphics
<7> [228.708518] i915 0000:00:02.0: pixel repeat: 0
<7> [228.708520] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [228.708522] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.708678] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [228.708835] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.708990] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [228.708992] i915 0000:00:02.0: vendor: Intel
<7> [228.708994] i915 0000:00:02.0: product: Integrated gfx
<7> [228.708995] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [228.708998] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.709153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [228.709310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.709501] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [228.709503] i915 0000:00:02.0: empty frame
<7> [228.709505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.709681] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [228.709838] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [228.710032] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [228.710190] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [228.710382] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [228.710561] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [228.710717] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [228.710872] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [228.711031] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [228.711188] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [228.711363] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [228.711551] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [228.711732] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [228.711916] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [228.712122] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [228.712281] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [228.712443] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [228.712641] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [228.712803] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [228.712964] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [228.713122] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [228.713281] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [228.713439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [228.713614] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [228.713776] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [228.713938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [228.714176] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [228.740606] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [228.741311] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [228.741498] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [228.742152] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [228.742346] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [228.742365] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [228.742589] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [228.747926] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [228.748171] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [228.748402] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [228.748615] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [228.748824] 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> [228.749159] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [228.749421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [228.749671] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [228.749924] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [228.750217] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [228.750469] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [228.750717] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [228.750962] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [228.751231] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [228.751477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [228.751723] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [228.751967] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [228.752219] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [228.752424] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [228.752626] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [228.752828] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [228.753031] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [228.753249] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [228.753450] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [228.753652] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [228.753852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [228.754054] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [228.754491] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [228.754767] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [228.755001] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [228.755274] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [228.755911] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [228.756089] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [228.756229] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [228.756367] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [228.756397] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [228.756466] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [228.756645] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [228.756807] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [228.756969] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [228.757124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [228.757323] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [228.757484] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [228.757645] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [228.757804] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [228.757963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [228.758122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [228.758284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [228.758457] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [228.758616] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [228.758774] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [228.758931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [228.759088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [228.759246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [228.759422] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [228.759579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [228.759735] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [228.759893] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [228.760097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [228.760254] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [228.760481] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [228.760650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [228.760806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [228.760963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [228.761128] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [228.761310] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [228.761493] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [228.761650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [228.761816] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [228.761973] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [228.762131] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [228.762286] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [228.762502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [228.762663] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [228.762825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [228.762991] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [228.763147] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [228.763303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [228.763470] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [228.763704] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [228.763861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [228.764029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.764195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.764356] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [228.764358] i915 0000:00:02.0: colorspace: RGB
<7> [228.764360] i915 0000:00:02.0: scan mode: Underscan
<7> [228.764361] i915 0000:00:02.0: colorimetry: No Data
<7> [228.764363] i915 0000:00:02.0: picture aspect: No Data
<7> [228.764365] i915 0000:00:02.0: active aspect: Same as Picture
<7> [228.764366] i915 0000:00:02.0: itc: No Data
<7> [228.764368] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [228.764370] i915 0000:00:02.0: quantization range: Full
<7> [228.764371] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [228.764373] i915 0000:00:02.0: video code: 0
<7> [228.764375] i915 0000:00:02.0: ycc quantization range: Full
<7> [228.764376] i915 0000:00:02.0: hdmi content type: Graphics
<7> [228.764378] i915 0000:00:02.0: pixel repeat: 0
<7> [228.764379] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [228.764381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [228.764553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.764716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.764872] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [228.764874] i915 0000:00:02.0: vendor: Intel
<7> [228.764876] i915 0000:00:02.0: product: Integrated gfx
<7> [228.764877] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [228.764879] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [228.765061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [228.765236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [228.765399] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [228.765401] i915 0000:00:02.0: empty frame
<7> [228.765404] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [228.765611] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [228.765770] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [228.765961] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [228.766117] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [228.766292] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [228.766461] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [228.766664] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [228.766823] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [228.767000] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [228.767166] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [228.767331] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [228.767537] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [228.767740] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [228.767937] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [228.768095] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [228.768251] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [228.768409] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [228.768602] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [228.768791] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [228.768960] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [228.769128] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [228.769286] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [228.769444] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [228.769604] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [228.769831] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [228.769996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [228.770154] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [228.770317] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [228.770319] i915 0000:00:02.0: colorspace: RGB
<7> [228.770321] i915 0000:00:02.0: scan mode: Underscan
<7> [228.770323] i915 0000:00:02.0: colorimetry: No Data
<7> [228.770324] i915 0000:00:02.0: picture aspect: No Data
<7> [228.770326] i915 0000:00:02.0: active aspect: Same as Picture
<7> [228.770328] i915 0000:00:02.0: itc: No Data
<7> [228.770329] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [228.770331] i915 0000:00:02.0: quantization range: Full
<7> [228.770332] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [228.770334] i915 0000:00:02.0: video code: 0
<7> [228.770336] i915 0000:00:02.0: ycc quantization range: Full
<7> [228.770337] i915 0000:00:02.0: hdmi content type: Graphics
<7> [228.770339] i915 0000:00:02.0: pixel repeat: 0
<7> [228.770341] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [228.770343] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [228.770344] i915 0000:00:02.0: vendor: Intel
<7> [228.770346] i915 0000:00:02.0: product: Integrated gfx
<7> [228.770348] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [228.770350] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [228.770352] i915 0000:00:02.0: empty frame
<7> [228.770353] 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> [228.770511] 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> [228.770668] 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> [228.770859] 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> [228.771016] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [228.771198] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [228.771371] 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> [228.771536] 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> [228.771694] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [228.771887] 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> [228.772046] 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> [228.772204] 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> [228.772389] 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> [228.772553] 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> [228.772717] 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> [228.772895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [228.773053] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [228.773209] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [228.773365] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [228.773522] 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> [228.773715] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x40000000 gamma_enable: 0 csc_enable: 0
<7> [228.773874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries
<7> [228.774061] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [228.774233] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.774393] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.774551] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.774722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [228.774905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [228.775103] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.775259] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.775422] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [228.775577] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [228.775732] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [228.775920] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:634] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [228.776098] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [228.776268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [228.776435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [228.776591] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [228.776747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [228.776916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [228.777108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [228.777272] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [228.777429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [228.778228] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [228.778402] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [228.778586] 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> [228.779193] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [228.779395] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [228.779556] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [228.779723] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [228.779887] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [228.780043] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [228.780216] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [228.780372] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [228.780561] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [228.780722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [228.780889] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [228.781054] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [228.781222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [228.781378] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [228.781539] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [228.781719] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [228.781875] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [228.782035] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [228.782198] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [228.782375] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [228.782532] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [228.782857] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [228.783116] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [228.783322] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [228.783551] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [228.783828] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [228.783947] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000d
<7> [228.784166] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [228.784733] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [228.785005] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [228.785599] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [228.785782] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [228.785797] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [228.789541] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [228.789709] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [228.794457] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [228.802784] 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> [228.802968] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [228.803154] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [228.803444] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [228.803697] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [228.805484] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000a
<7> [228.805665] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: creating arb_session after invalidation
<7> [228.835615] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP ARB session is alive
<7> [229.731499] 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> [229.731499] 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> [229.731510] 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> [229.731510] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling TC_cold_off
<7> [229.732221] i915 0000:00:02.0: [drm:tgl_tc_cold_request [i915]] TC cold unblock succeeded
<7> [229.732843] 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> [239.761934] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [239.762214] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [239.762731] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [239.763297] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [239.763760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [239.764231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [239.764697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [239.765200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [239.765660] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [239.766153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [239.766613] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [239.767119] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [239.767598] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [239.767789] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [239.767964] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [239.768122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1083, found 0)
<7> [239.768279] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1088, found 0)
<7> [239.768435] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1144, found 0)
<7> [239.768591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1144, found 0)
<7> [239.768753] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [239.768925] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [239.769084] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [239.769241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [239.769398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [239.769555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [239.769711] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [239.769876] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [239.770033] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1083, found 0)
<7> [239.770188] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1088, found 0)
<7> [239.770343] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1144, found 0)
<7> [239.770499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1144, found 0)
<7> [239.770654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [239.770809] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [239.770984] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [239.771145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected yes, found no)
<7> [239.771300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected yes, found no)
<7> [239.771456] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [239.771611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 285500, found 0)
<7> [239.771766] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 285500, found 0)
<7> [239.771935] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 285500, found 0)
<7> [239.772091] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000071, found 0x00000000)
<7> [239.772246] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [239.772402] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.772556] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [239.772558] i915 0000:00:02.0: colorspace: RGB
<7> [239.772560] i915 0000:00:02.0: scan mode: Underscan
<7> [239.772561] i915 0000:00:02.0: colorimetry: No Data
<7> [239.772563] i915 0000:00:02.0: picture aspect: No Data
<7> [239.772564] i915 0000:00:02.0: active aspect: Same as Picture
<7> [239.772566] i915 0000:00:02.0: itc: No Data
<7> [239.772567] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [239.772569] i915 0000:00:02.0: quantization range: Full
<7> [239.772570] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [239.772572] i915 0000:00:02.0: video code: 0
<7> [239.772573] i915 0000:00:02.0: ycc quantization range: Full
<7> [239.772574] i915 0000:00:02.0: hdmi content type: Graphics
<7> [239.772576] i915 0000:00:02.0: pixel repeat: 0
<7> [239.772577] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [239.772579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.772734] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [239.772897] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.773051] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [239.773053] i915 0000:00:02.0: vendor: Intel
<7> [239.773055] i915 0000:00:02.0: product: Integrated gfx
<7> [239.773056] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [239.773058] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.773213] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [239.773366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.773520] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [239.773521] i915 0000:00:02.0: empty frame
<7> [239.773523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.773677] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [239.773833] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [239.774042] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [239.774199] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [239.774389] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 1993) -> ( 0 - 0), size 1993 -> 0
<7> [239.774543] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (1993 - 2048) -> ( 0 - 0), size 55 -> 0
<7> [239.774697] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [239.774851] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [239.775025] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [239.775181] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [239.775358] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [239.775521] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 0
<7> [239.775699] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 192000 kHz -> 0 kHz
<7> [239.775878] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 17844 kHz -> 0 kHz
<7> [239.776080] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [239.776237] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 172800 kHz, actual 172800 kHz
<7> [239.776393] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [239.776554] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [239.776714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [239.776873] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [239.777047] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [239.777206] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [239.777363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [239.777520] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [239.777676] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [239.777833] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [239.778088] i915 0000:00:02.0: [drm:intel_audio_codec_disable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Disable audio codec on [CRTC:170:pipe A]
<7> [239.798158] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [239.798721] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [239.798967] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [239.799475] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [239.799691] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [239.799706] i915 0000:00:02.0: [drm:intel_ddi_disable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to reset sink scrambling/TMDS bit clock ratio
<7> [239.799895] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [239.805198] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [239.805411] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [239.805599] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [239.805771] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [239.805940] 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> [239.806208] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [239.806385] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [239.806544] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [239.806701] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [239.806858] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [239.807080] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [239.807314] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [239.807507] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [239.807711] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [239.807906] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [239.808101] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [239.808363] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [239.808557] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [239.808756] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [239.808956] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [239.809144] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [239.809353] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [239.809579] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [239.809773] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [239.809963] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [239.810126] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [239.810293] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [239.810622] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [239.810860] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [239.811047] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [239.811256] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [239.811873] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [239.812057] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [239.812190] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [239.812363] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [239.812393] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:587:HDMI-A-1]
<7> [239.812458] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:587:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [239.812639] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [239.812799] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [239.812994] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI B/PHY B] [CRTC:170:pipe A]
<7> [239.813158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [239.813344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [239.813511] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [239.813676] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [239.813841] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [239.814002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [239.814194] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [239.814371] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [239.814533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [239.814696] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [239.814853] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [239.815009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [239.815193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [239.815383] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [239.815548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1083)
<7> [239.815712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1088)
<7> [239.815870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1144)
<7> [239.816029] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1144)
<7> [239.816185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [239.816381] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [239.816558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [239.816721] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [239.816877] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [239.817044] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [239.817199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [239.817369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [239.817556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1083)
<7> [239.817712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1088)
<7> [239.817875] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1144)
<7> [239.818034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1144)
<7> [239.818191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [239.818359] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [239.818515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [239.818707] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_hdmi_sink (expected no, found yes)
<7> [239.818870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in has_infoframe (expected no, found yes)
<7> [239.819035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [239.819202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 285500)
<7> [239.819460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 285500)
<7> [239.819620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 285500)
<7> [239.819808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in infoframes.enable (expected 0x00000000, found 0x00000071)
<7> [239.819974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in avi infoframe
<7> [239.820140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.820296] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.820506] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [239.820508] i915 0000:00:02.0: colorspace: RGB
<7> [239.820510] i915 0000:00:02.0: scan mode: Underscan
<7> [239.820512] i915 0000:00:02.0: colorimetry: No Data
<7> [239.820514] i915 0000:00:02.0: picture aspect: No Data
<7> [239.820515] i915 0000:00:02.0: active aspect: Same as Picture
<7> [239.820517] i915 0000:00:02.0: itc: No Data
<7> [239.820518] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [239.820520] i915 0000:00:02.0: quantization range: Full
<7> [239.820522] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [239.820523] i915 0000:00:02.0: video code: 0
<7> [239.820525] i915 0000:00:02.0: ycc quantization range: Full
<7> [239.820527] i915 0000:00:02.0: hdmi content type: Graphics
<7> [239.820528] i915 0000:00:02.0: pixel repeat: 0
<7> [239.820530] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [239.820532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in spd infoframe
<7> [239.820689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.820846] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.821003] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [239.821005] i915 0000:00:02.0: vendor: Intel
<7> [239.821006] i915 0000:00:02.0: product: Integrated gfx
<7> [239.821008] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [239.821009] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hdmi infoframe
<7> [239.821199] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [239.821369] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [239.821531] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [239.821533] i915 0000:00:02.0: empty frame
<7> [239.821535] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [239.821732] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [239.821892] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [239.822087] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 1993), size 0 -> 1993
<7> [239.822252] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (1993 - 2048), size 0 -> 55
<7> [239.822450] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [239.822609] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 8, 8, 8, 8, 11, 16, 16, 0, 0, 0
<7> [239.822767] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 129, 129, 129, 129, 177, 257, 257, 30, 0, 0
<7> [239.822933] i915 0000:00:02.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 143, 143, 143, 143, 196, 284, 284, 31, 0, 0
<7> [239.823110] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 1142000 num active planes 1
<7> [239.823275] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 14993 required 1142
<7> [239.823452] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [239.823632] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 192000 kHz
<7> [239.823808] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 17844 kHz
<7> [239.823993] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] Modeset required for cdclk change
<7> [239.824152] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 192000 kHz, actual 192000 kHz
<7> [239.824310] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [239.824486] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [239.824651] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [239.824817] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [239.824979] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [239.825140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [239.825300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [239.825478] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [239.825638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [239.825796] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [239.825955] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 1, infoframes: 1, infoframes enabled: 0x71
<7> [239.826117] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] GCP: 0x0
<7> [239.826277] i915 0000:00:02.0: HDMI infoframe: Auxiliary Video Information (AVI), version 2, length 13
<7> [239.826279] i915 0000:00:02.0: colorspace: RGB
<7> [239.826281] i915 0000:00:02.0: scan mode: Underscan
<7> [239.826282] i915 0000:00:02.0: colorimetry: No Data
<7> [239.826284] i915 0000:00:02.0: picture aspect: No Data
<7> [239.826285] i915 0000:00:02.0: active aspect: Same as Picture
<7> [239.826287] i915 0000:00:02.0: itc: No Data
<7> [239.826288] i915 0000:00:02.0: extended colorimetry: xvYCC 601
<7> [239.826289] i915 0000:00:02.0: quantization range: Full
<7> [239.826291] i915 0000:00:02.0: nups: Unknown Non-uniform Scaling
<7> [239.826292] i915 0000:00:02.0: video code: 0
<7> [239.826294] i915 0000:00:02.0: ycc quantization range: Full
<7> [239.826295] i915 0000:00:02.0: hdmi content type: Graphics
<7> [239.826296] i915 0000:00:02.0: pixel repeat: 0
<7> [239.826298] i915 0000:00:02.0: bar top 0, bottom 0, left 0, right 0
<7> [239.826300] i915 0000:00:02.0: HDMI infoframe: Source Product Description (SPD), version 1, length 25
<7> [239.826301] i915 0000:00:02.0: vendor: Intel
<7> [239.826303] i915 0000:00:02.0: product: Integrated gfx
<7> [239.826304] i915 0000:00:02.0: source device information: PC General (0x9)
<7> [239.826306] i915 0000:00:02.0: HDMI infoframe: Vendor, version 1, length 4
<7> [239.826308] i915 0000:00:02.0: empty frame
<7> [239.826310] 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> [239.826488] 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> [239.826647] 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> [239.826803] 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> [239.826959] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [239.827116] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [239.827272] 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> [239.827441] 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> [239.827597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: dc balance: no, vmin: 0 vmax: 0 guardband: 0, slope: 0 max increase: 0 max decrease: 0 vblank target: 0
<7> [239.827754] 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> [239.827912] 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> [239.828068] 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> [239.828225] 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> [239.828383] 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> [239.828555] 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> [239.828713] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 59, ips linetime: 0
<7> [239.828869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [239.829026] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [239.829183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [239.829338] 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> [239.829512] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x40000000 gamma_enable: 0 csc_enable: 0
<7> [239.829669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries
<7> [239.829825] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [239.829981] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.830138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.830294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.830460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [239.830618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [239.830774] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.830930] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.831086] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [239.831241] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [239.831396] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [239.831567] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:634] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [239.831725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [239.831881] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [239.832037] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [239.832194] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [239.832349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [239.832522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [239.832679] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [239.832835] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [239.832992] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [239.833279] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [239.833462] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [239.833639] 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> [239.833890] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI B/PHY B]
<7> [239.834050] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:596:DDI TC1/PHY TC1]
<7> [239.834210] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:598:DP-MST A]
<7> [239.834366] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:599:DP-MST B]
<7> [239.834548] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:600:DP-MST C]
<7> [239.834710] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:601:DP-MST D]
<7> [239.834869] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI TC2/PHY TC2]
<7> [239.835027] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:608:DP-MST A]
<7> [239.835184] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DP-MST B]
<7> [239.835340] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DP-MST C]
<7> [239.835505] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST D]
<7> [239.835661] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DDI TC3/PHY TC3]
<7> [239.835814] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:617:DP-MST A]
<7> [239.835969] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:618:DP-MST B]
<7> [239.836124] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:619:DP-MST C]
<7> [239.836278] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:620:DP-MST D]
<7> [239.836431] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DDI TC4/PHY TC4]
<7> [239.836597] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST A]
<7> [239.836752] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST B]
<7> [239.836906] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:628:DP-MST C]
<7> [239.837060] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:629:DP-MST D]
<7> [239.837339] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [239.837600] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [239.837763] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [239.837988] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [239.838264] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [239.838369] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000d
<7> [239.838609] i915 0000:00:02.0: [drm:intel_hdmi_handle_sink_scrambling [i915]] [CONNECTOR:587:HDMI-A-1] scrambling=no, TMDS bit clock ratio=1/10
<7> [239.839173] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [239.839436] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK on first message, retry
<7> [239.839993] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpb] NAK for addr: 0054 w(1)
<7> [239.840202] i915 0000:00:02.0: [drm:drm_scdc_set_high_tmds_clock_ratio [drm_display_helper]] [CONNECTOR:587:HDMI-A-1] Failed to read TMDS config: -6
<7> [239.840217] i915 0000:00:02.0: [drm:intel_ddi_enable [i915]] [CONNECTOR:587:HDMI-A-1] Failed to configure sink scrambling/TMDS bit clock ratio
<7> [239.843525] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [239.843714] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [239.848839] i915 0000:00:02.0: [drm:intel_audio_codec_enable [i915]] [CONNECTOR:587:HDMI-A-1][ENCODER:586:DDI B/PHY B] Enable audio codec on [CRTC:170:pipe A], 64 bytes ELD
<7> [239.857166] 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> [239.857351] i915 0000:00:02.0: [drm:audio_config_hdmi_pixel_clock [i915]] Configuring HDMI audio for pixel clock 25200 (0x00010000)
<7> [239.857513] i915 0000:00:02.0: [drm:hsw_audio_config_update [i915]] using automatic N
<7> [239.857810] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:587:HDMI-A-1]
<7> [239.858066] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [239.860122] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000a
<7> [239.860288] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: creating arb_session after invalidation
<7> [239.889747] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP ARB session is alive
<6> [247.813264] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-02-24 00:58:10