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

Result: Fail

integration-manifest git-log-oneline i915_display_info21 igt_runner21 runtimes21 results21.json results21-i915-load.json guc_logs21.tar i915_display_info_post_exec21 boot21 dmesg21

DetailValue
Duration 24.08 seconds
Hostname 
shard-rkl-7
Igt-Version 
IGT-Version: 2.4-g98b65acc4 (x86_64) (Linux: 7.1.0-rc4-Patchwork_167094v2-g60d51bdeabf7+ x86_64)
Out 
Using IGT_SRANDOM=1779552010 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: flip-vs-blocking-wf-vblank
Starting dynamic subtest: A-HDMI-A2
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19807.4us +- 6.985us accuracy 0.11%
vblank interval differs from modeline! expected 18973.8us, measured 19807us +- 6.985us, difference 833.6us (119.4 sigma)
Event flip: expected 510, counted 520, passrate = 7.69%, encoder type 2
Event vblank: expected 510, counted 520, passrate = 7.69%, encoder type 2
Expected frametime: 18974us; measured 19128.7us +- 7.310us accuracy 0.11%
vblank interval differs from modeline! expected 18973.8us, measured 19129us +- 7.310us, difference 154.9us (21.2 sigma)
Event flip: expected 528, counted 530, passrate = 77.36%, encoder type 2
Event vblank: expected 528, counted 530, passrate = 73.58%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2106 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A2: FAIL (24.077s)
Err 
Starting subtest: flip-vs-blocking-wf-vblank
Starting dynamic subtest: A-HDMI-A2
[283.515259] (kms_flip:2999) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
[283.515296] (kms_flip:2999) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A2 failed.
**** DEBUG ****
[273.120824] (kms_flip:2999) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
[273.121084] (kms_flip:2999) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
[273.439952] (kms_flip:2999) INFO: Expected frametime: 18974us; measured 19128.7us +- 7.310us accuracy 0.11%
[273.440063] (kms_flip:2999) INFO: vblank interval differs from modeline! expected 18973.8us, measured 19129us +- 7.310us, difference 154.9us (21.2 sigma)
[273.648740] (kms_flip:2999) DEBUG: name = flip
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 274.123627
current_received_ts = 274.295441
current_seq = 9836
count = 0
seq_step = 1
[273.648758] (kms_flip:2999) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 274.295624
current_received_ts = 274.295380
current_seq = 9845
count = 0
seq_step = 10
[273.838235] (kms_flip:2999) DEBUG: name = flip
last_ts = 274.123627
last_received_ts = 274.295441
last_seq = 9836
current_ts = 274.314758
current_received_ts = 274.486298
current_seq = 9846
count = 1
seq_step = 1
[273.838256] (kms_flip:2999) DEBUG: flip ts/seq: last 274.123627/9836, current 274.314758/9846: elapsed=191112.0us expected=191286.9us +- 956.4us, error 0.1%
[273.838264] (kms_flip:2999) DEBUG: name = vblank
last_ts = 274.295624
last_received_ts = 274.295380
last_seq = 9845
current_ts = 274.486755
current_received_ts = 274.486237
current_seq = 9855
count = 1
seq_step = 10
[273.838271] (kms_flip:2999) DEBUG: vblank ts/seq: last 274.295624/9845, current 274.486755/9855: elapsed=191109.0us expected=191286.9us +- 956.4us, error 0.1%
[274.028127] (kms_flip:2999) DEBUG: name = flip
last_ts = 274.314758
last_received_ts = 274.486298
last_seq = 9846
current_ts = 274.505859
current_received_ts = 274.677551
current_seq = 9856
count = 2
seq_step = 1
[274.028149] (kms_flip:2999) DEBUG: flip ts/seq: last 274.314758/9846, current 274.505859/9856: elapsed=191093.0us expected=191286.9us +- 956.4us, error 0.1%
[274.028156] (kms_flip:2999) DEBUG: name = vblank
last_ts = 274.486755
last_received_ts = 274.486237
last_seq = 9855
current_ts = 274.677856
current_received_ts = 274.677490
current_seq = 9865
count = 2
seq_step = 10
[274.028163] (kms_flip:2999) DEBUG: vblank ts/seq: last 274.486755/9855, current 274.677856/9865: elapsed=191104.0us expected=191286.9us +- 956.4us, error 0.1%
[274.217796] (kms_flip:2999) DEBUG: name = flip
last_ts = 274.505859
last_received_ts = 274.677551
last_seq = 9856
current_ts = 274.696960
current_received_ts = 274.868591
current_seq = 9866
count = 3
seq_step = 1
[274.217817] (kms_flip:2999) DEBUG: flip ts/seq: last 274.505859/9856, current 274.696960/9866: elapsed=191112.0us expected=191286.9us +- 956.4us, error 0.1%
[274.217824] (kms_flip:2999) DEBUG: name = vblank
last_ts = 274.677856
last_received_ts = 274.677490
last_seq = 9865
current_ts = 274.868927
current_received_ts = 274.868530
current_seq = 9875
count = 3
seq_step = 10
[274.217832] (kms_flip:2999) DEBUG: vblank ts/seq: last 274.677856/9865, current 274.868927/9875: elapsed=191085.0us expected=191286.9us +- 956.4us, error 0.1%
[274.407530] (kms_flip:2999) DEBUG: name = flip
last_ts = 274.696960
last_received_ts = 274.868591
last_seq = 9866
current_ts = 274.888062
current_received_ts = 275.059662
current_seq = 9876
count = 4
seq_step = 1
[274.407551] (kms_flip:2999) DEBUG: flip ts/seq: last 274.696960/9866, current 274.888062/9876: elapsed=191093.0us expected=191286.9us +- 956.4us, error 0.1%
[274.407559] (kms_flip:2999) DEBUG: name = vblank
last_ts = 274.868927
last_received_ts = 274.868530
last_seq = 9875
current_ts = 275.060059
current_received_ts = 275.059631
current_seq = 9885
count = 4
seq_step = 10
[274.407566] (kms_flip:2999) DEBUG: vblank ts/seq: last 274.868927/9875, current 275.060059/9885: elapsed=191116.0us expected=191286.9us +- 956.4us, error 0.1%
[274.597349] (kms_flip:2999) DEBUG: name = flip
last_ts = 274.888062
last_received_ts = 275.059662
last_seq = 9876
current_ts = 275.079163
current_received_ts = 275.250763
current_seq = 9886
count = 5
seq_step = 1
[274.597371] (kms_flip:2999) DEBUG: flip ts/seq: last 274.888062/9876, current 275.079163/9886: elapsed=191097.0us expected=191286.9us +- 956.4us, error 0.1%
[274.597378] (kms_flip:2999) DEBUG: name = vblank
last_ts = 275.060059
last_received_ts = 275.059631
last_seq = 9885
current_ts = 275.251038
current_received_ts = 275.250702
current_seq = 9895
count = 5
seq_step = 10
[274.597386] (kms_flip:2999) DEBUG: vblank ts/seq: last 275.060059/9885, current 275.251038/9895: elapsed=191001.0us expected=191286.9us +- 956.4us, error 0.1%
[274.787154] (kms_flip:2999) DEBUG: name = flip
last_ts = 275.079163
last_received_ts = 275.250763
last_seq = 9886
current_ts = 275.270142
current_received_ts = 275.441772
current_seq = 9896
count = 6
seq_step = 1
[274.787175] (kms_flip:2999) DEBUG: flip ts/seq: last 275.079163/9886, current 275.270142/9896: elapsed=190991.0us expected=191286.9us +- 956.4us, error 0.2%
[274.787183] (kms_flip:2999) DEBUG: name = vblank
last_ts = 275.251038
last_received_ts = 275.250702
last_seq = 9895
current_ts = 275.441986
current_received_ts = 275.441711
current_seq = 9905
count = 6
seq_step = 10
[274.787191] (kms_flip:2999) DEBUG: vblank ts/seq: last 275.251038/9895, current 275.441986/9905: elapsed=190949.0us expected=191286.9us +- 956.4us, error 0.2%
[274.976777] (kms_flip:2999) DEBUG: name = flip
last_ts = 275.270142
last_received_ts = 275.441772
last_seq = 9896
current_ts = 275.461090
current_received_ts = 275.632599
current_seq = 9906
count = 7
seq_step = 1
[274.976798] (kms_flip:2999) DEBUG: flip ts/seq: last 275.270142/9896, current 275.461090/9906: elapsed=190950.0us expected=191286.9us +- 956.4us, error 0.2%
[274.976805] (kms_flip:2999) DEBUG: name = vblank
last_ts = 275.441986
last_received_ts = 275.441711
last_seq = 9905
current_ts = 275.632935
current_received_ts = 275.632538
current_seq = 9915
count = 7
seq_step = 10
[274.976813] (kms_flip:2999) DEBUG: vblank ts/seq: last 275.441986/9905, current 275.632935/9915: elapsed=190933.0us expected=191286.9us +- 956.4us, error 0.2%
[275.166634] (kms_flip:2999) DEBUG: name = flip
last_ts = 275.461090
last_received_ts = 275.632599
last_seq = 9906
current_ts = 275.652039
current_received_ts = 275.823669
current_seq = 9916
count = 8
seq_step = 1
[275.166655] (kms_flip:2999) DEBUG: flip ts/seq: last 275.461090/9906, current 275.652039/9916: elapsed=190937.0us expected=191286.9us +- 956.4us, error 0.2%
[275.166662] (kms_flip:2999) DEBUG: name = vblank
last_ts = 275.632935
last_received_ts = 275.632538
last_seq = 9915
current_ts = 275.823883
current_received_ts = 275.823608
current_seq = 9925
count = 8
seq_step = 10
[275.166670] (kms_flip:2999) DEBUG: vblank ts/seq: last 275.632935/9915, current 275.823883/9925: elapsed=190955.0us expected=191286.9us +- 956.4us, error 0.2%
[275.356245] (kms_flip:2999) DEBUG: name = flip
last_ts = 275.652039
last_received_ts = 275.823669
last_seq = 9916
current_ts = 275.842987
current_received_ts = 276.014465
current_seq = 9926
count = 9
seq_step = 1
[275.356265] (kms_flip:2999) DEBUG: flip ts/seq: last 275.652039/9916, current 275.842987/9926: elapsed=190953.0us expected=191286.9us +- 956.4us, error 0.2%
[275.356272] (kms_flip:2999) DEBUG: name = vblank
last_ts = 275.823883
last_received_ts = 275.823608
last_seq = 9925
current_ts = 276.014832
current_received_ts = 276.014435
current_seq = 9935
count = 9
seq_step = 10
[275.356280] (kms_flip:2999) DEBUG: vblank ts/seq: last 275.823883/9925, current 276.014832/9935: elapsed=190932.0us expected=191286.9us +- 956.4us, error 0.2%
[275.546111] (kms_flip:2999) DEBUG: name = flip
last_ts = 275.842987
last_received_ts = 276.014465
last_seq = 9926
current_ts = 276.033905
current_received_ts = 276.205505
current_seq = 9936
count = 10
seq_step = 1
[275.546132] (kms_flip:2999) DEBUG: flip ts/seq: last 275.842987/9926, current 276.033905/9936: elapsed=190935.0us expected=191286.9us +- 956.4us, error 0.2%
[275.546140] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.014832
last_received_ts = 276.014435
last_seq = 9935
current_ts = 276.205719
current_received_ts = 276.205444
current_seq = 9945
count = 10
seq_step = 10
[275.546147] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.014832/9935, current 276.205719/9945: elapsed=190896.0us expected=191286.9us +- 956.4us, error 0.2%
[275.735844] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.033905
last_received_ts = 276.205505
last_seq = 9936
current_ts = 276.224792
current_received_ts = 276.396301
current_seq = 9946
count = 11
seq_step = 1
[275.735865] (kms_flip:2999) DEBUG: flip ts/seq: last 276.033905/9936, current 276.224792/9946: elapsed=190870.0us expected=191286.9us +- 956.4us, error 0.2%
[275.735872] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.205719
last_received_ts = 276.205444
last_seq = 9945
current_ts = 276.396515
current_received_ts = 276.396240
current_seq = 9955
count = 11
seq_step = 10
[275.735880] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.205719/9945, current 276.396515/9955: elapsed=190805.0us expected=191286.9us +- 956.4us, error 0.3%
[275.925614] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.224792
last_received_ts = 276.396301
last_seq = 9946
current_ts = 276.415588
current_received_ts = 276.587128
current_seq = 9956
count = 12
seq_step = 1
[275.925634] (kms_flip:2999) DEBUG: flip ts/seq: last 276.224792/9946, current 276.415588/9956: elapsed=190805.0us expected=191286.9us +- 956.4us, error 0.3%
[275.925642] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.396515
last_received_ts = 276.396240
last_seq = 9955
current_ts = 276.587341
current_received_ts = 276.587067
current_seq = 9965
count = 12
seq_step = 10
[275.925650] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.396515/9955, current 276.587341/9965: elapsed=190808.0us expected=191286.9us +- 956.4us, error 0.3%
[276.115335] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.415588
last_received_ts = 276.587128
last_seq = 9956
current_ts = 276.606415
current_received_ts = 276.777924
current_seq = 9966
count = 13
seq_step = 1
[276.115356] (kms_flip:2999) DEBUG: flip ts/seq: last 276.415588/9956, current 276.606415/9966: elapsed=190811.0us expected=191286.9us +- 956.4us, error 0.2%
[276.115363] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.587341
last_received_ts = 276.587067
last_seq = 9965
current_ts = 276.778107
current_received_ts = 276.777863
current_seq = 9975
count = 13
seq_step = 10
[276.115371] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.587341/9965, current 276.778107/9975: elapsed=190791.0us expected=191286.9us +- 956.4us, error 0.3%
[276.304920] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.606415
last_received_ts = 276.777924
last_seq = 9966
current_ts = 276.797211
current_received_ts = 276.968567
current_seq = 9976
count = 14
seq_step = 1
[276.304942] (kms_flip:2999) DEBUG: flip ts/seq: last 276.606415/9966, current 276.797211/9976: elapsed=190803.0us expected=191286.9us +- 956.4us, error 0.3%
[276.304950] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.778107
last_received_ts = 276.777863
last_seq = 9975
current_ts = 276.968933
current_received_ts = 276.968506
current_seq = 9985
count = 14
seq_step = 10
[276.304958] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.778107/9975, current 276.968933/9985: elapsed=190810.0us expected=191286.9us +- 956.4us, error 0.2%
[276.494660] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.797211
last_received_ts = 276.968567
last_seq = 9976
current_ts = 276.988007
current_received_ts = 277.159363
current_seq = 9986
count = 15
seq_step = 1
[276.494680] (kms_flip:2999) DEBUG: flip ts/seq: last 276.797211/9976, current 276.988007/9986: elapsed=190805.0us expected=191286.9us +- 956.4us, error 0.3%
[276.494689] (kms_flip:2999) DEBUG: name = vblank
last_ts = 276.968933
last_received_ts = 276.968506
last_seq = 9985
current_ts = 277.159729
current_received_ts = 277.159302
current_seq = 9995
count = 15
seq_step = 10
[276.494697] (kms_flip:2999) DEBUG: vblank ts/seq: last 276.968933/9985, current 277.159729/9995: elapsed=190791.0us expected=191286.9us +- 956.4us, error 0.3%
[276.684536] (kms_flip:2999) DEBUG: name = flip
last_ts = 276.988007
last_received_ts = 277.159363
last_seq = 9986
current_ts = 277.178772
current_received_ts = 277.350189
current_seq = 9996
count = 16
seq_step = 1
[276.684557] (kms_flip:2999) DEBUG: flip ts/seq: last 276.988007/9986, current 277.178772/9996: elapsed=190777.0us expected=191286.9us +- 956.4us, error 0.3%
[276.684565] (kms_flip:2999) DEBUG: name = vblank
last_ts = 277.159729
last_received_ts = 277.159302
last_seq = 9995
current_ts = 277.350403
current_received_ts = 277.350128
current_seq = 10005
count = 16
seq_step = 10
[276.684573] (kms_flip:2999) DEBUG: vblank ts/seq: last 277.159729/9995, current 277.350403/10005: elapsed=190690.0us expected=191286.9us +- 956.4us, error 0.3%
[276.874273] (kms_flip:2999) DEBUG: name = flip
last_ts = 277.178772
last_received_ts = 277.350189
last_seq = 9996
current_ts = 277.369476
current_received_ts = 277.540863
current_seq = 10006
count = 17
seq_step = 1
[276.874295] (kms_flip:2999) DEBUG: flip ts/seq: last 277.178772/9996, current 277.369476/10006: elapsed=190685.0us expected=191286.9us +- 956.4us, error 0.3%
[276.874304] (kms_flip:2999) DEBUG: name = vblank
last_ts = 277.350403
last_received_ts = 277.350128
last_seq = 10005
current_ts = 277.541107
current_received_ts = 277.540802
current_seq = 10015
count = 17
seq_step = 10
[276.874311] (kms_flip:2999) DEBUG: vblank ts/seq: last 277.350403/10005, current 277.541107/10015: elapsed=190683.0us expected=191286.9us +- 956.4us, error 0.3%
[277.064012] (kms_flip:2999) DEBUG: name = flip
last_ts = 277.369476
last_received_ts = 277.540863
last_seq = 10006
current_ts = 277.560150
current_received_ts = 277.731567
current_seq = 10016
count = 18
seq_step = 1
[277.064033] (kms_flip:2999) DEBUG: flip ts/seq: last 277.369476/10006, current 277.560150/10016: elapsed=190690.0us expected=191286.9us +- 956.4us, error 0.3%
[277.064041] (kms_flip:2999) DEBUG: name = vblank
last_ts = 277.541107
last_received_ts = 277.540802
last_seq = 10015
current_ts = 277.731781
current_received_ts = 277.731506
current_seq = 10025
count = 18
seq_step = 10
[277.064049] (kms_flip:2999) DEBUG: vblank ts/seq: last 277.541107/10015, current 277.731781/10025: elapsed=190684.0us expected=191286.9us +- 956.4us, error 0.3%
[277.253642] (kms_flip:2999) DEBUG: name = flip
last_ts = 277.560150
last_received_ts = 277.731567
last_seq = 10016
current_ts = 277.750854
current_received_ts = 277.922119
current_seq = 10026
count = 19
seq_step = 1
[277.253662] (kms_flip:2999) DEBUG: flip ts/seq: last 277.560150/10016, current 277.750854/10026: elapsed=190684.0us expected=191286.9us +- 956.4us, error 0.3%
[277.253670] (kms_flip:2999) DEBUG: name = vblank
last_ts = 277.731781
last_received_ts = 277.731506
last_seq = 10025
current_ts = 277.922455
current_received_ts = 277.922089
current_seq = 10035
count = 19
seq_step = 10
[277.253677] (kms_flip:2999) DEBUG: vblank ts/seq: last 277.731781/10025, current 277.922455/10035: elapsed=190679.0us expected=191286.9us +- 956.4us, error 0.3%
[277.443317] (kms_flip:2999) DEBUG: name = flip
last_ts = 277.750854
last_received_ts = 277.922119
last_seq = 10026
current_ts = 277.941528
current_received_ts = 278.112762
current_seq = 10036
count = 20
seq_step = 1
[277.443339] (kms_flip:2999) DEBUG: flip ts/seq: last 277.750854/10026, current 277.941528/10036: elapsed=190679.0us expected=191286.9us +- 956.4us, error 0.3%
[277.443347] (kms_flip:2999) DEBUG: name = vblank
last_ts = 277.922455
last_received_ts = 277.922089
last_seq = 10035
current_ts = 278.113129
current_received_ts = 278.112701
current_seq = 10045
count = 20
seq_step = 10
[277.443355] (kms_flip:2999) DEBUG: vblank ts/seq: last 277.922455/10035, current 278.113129/10045: elapsed=190672.0us expected=191286.9us +- 956.4us, error 0.3%
[277.633225] (kms_flip:2999) DEBUG: name = flip
last_ts = 277.941528
last_received_ts = 278.112762
last_seq = 10036
current_ts = 278.132202
current_received_ts = 278.303497
current_seq = 10046
count = 21
seq_step = 1
[277.633246] (kms_flip:2999) DEBUG: flip ts/seq: last 277.941528/10036, current 278.132202/10046: elapsed=190686.0us expected=191286.9us +- 956.4us, error 0.3%
[277.633254] (kms_flip:2999) DEBUG: name = vblank
last_ts = 278.113129
last_received_ts = 278.112701
last_seq = 10045
current_ts = 278.303741
current_received_ts = 278.303436
current_seq = 10055
count = 21
seq_step = 10
[277.633262] (kms_flip:2999) DEBUG: vblank ts/seq: last 278.113129/10045, current 278.303741/10055: elapsed=190601.0us expected=191286.9us +- 956.4us, error 0.4%
[277.822810] (kms_flip:2999) DEBUG: name = flip
last_ts = 278.132202
last_received_ts = 278.303497
last_seq = 10046
current_ts = 278.322784
current_received_ts = 278.493927
current_seq = 10056
count = 22
seq_step = 1
[277.822830] (kms_flip:2999) DEBUG: flip ts/seq: last 278.132202/10046, current 278.322784/10056: elapsed=190567.0us expected=191286.9us +- 956.4us, error 0.4%
[277.822837] (kms_flip:2999) DEBUG: name = vblank
last_ts = 278.303741
last_received_ts = 278.303436
last_seq = 10055
current_ts = 278.494293
current_received_ts = 278.493866
current_seq = 10065
count = 22
seq_step = 10
[277.822844] (kms_flip:2999) DEBUG: vblank ts/seq: last 278.303741/10055, current 278.494293/10065: elapsed=190575.0us expected=191286.9us +- 956.4us, error 0.4%
[278.012693] (kms_flip:2999) DEBUG: name = flip
last_ts = 278.322784
last_received_ts = 278.493927
last_seq = 10056
current_ts = 278.513367
current_received_ts = 278.684662
current_seq = 10066
count = 23
seq_step = 1
[278.012715] (kms_flip:2999) DEBUG: flip ts/seq: last 278.322784/10056, current 278.513367/10066: elapsed=190580.0us expected=191286.9us +- 956.4us, error 0.4%
[278.012724] (kms_flip:2999) DEBUG: name = vblank
last_ts = 278.494293
last_received_ts = 278.493866
last_seq = 10065
current_ts = 278.684875
current_received_ts = 278.684601
current_seq = 10075
count = 23
seq_step = 10
[278.012731] (kms_flip:2999) DEBUG: vblank ts/seq: last 278.494293/10065, current 278.684875/10075: elapsed=190578.0us expected=191286.9us +- 956.4us, error 0.4%
[278.202322] (kms_flip:2999) DEBUG: name = flip
last_ts = 278.513367
last_received_ts = 278.684662
last_seq = 10066
current_ts = 278.703949
current_received_ts = 278.875122
current_seq = 10076
count = 24
seq_step = 1
[278.202343] (kms_flip:2999) DEBUG: flip ts/seq: last 278.513367/10066, current 278.703949/10076: elapsed=190583.0us expected=191286.9us +- 956.4us, error 0.4%
[278.202351] (kms_flip:2999) DEBUG: name = vblank
last_ts = 278.684875
last_received_ts = 278.684601
last_seq = 10075
current_ts = 278.875458
current_received_ts = 278.875061
current_seq = 10085
count = 24
seq_step = 10
[278.202359] (kms_flip:2999) DEBUG: vblank ts/seq: last 278.684875/10075, current 278.875458/10085: elapsed=190572.0us expected=191286.9us +- 956.4us, error 0.4%
[278.391994] (kms_flip:2999) DEBUG: name = flip
last_ts = 278.703949
last_received_ts = 278.875122
last_seq = 10076
current_ts = 278.894501
current_received_ts = 279.065643
current_seq = 10086
count = 25
seq_step = 1
[278.392015] (kms_flip:2999) DEBUG: flip ts/seq: last 278.703949/10076, current 278.894501/10086: elapsed=190574.0us expected=191286.9us +- 956.4us, error 0.4%
[278.392023] (kms_flip:2999) DEBUG: name = vblank
last_ts = 278.875458
last_received_ts = 278.875061
last_seq = 10085
current_ts = 279.066010
current_received_ts = 279.065582
current_seq = 10095
count = 25
seq_step = 10
[278.392030] (kms_flip:2999) DEBUG: vblank ts/seq: last 278.875458/10085, current 279.066010/10095: elapsed=190567.0us expected=191286.9us +- 956.4us, error 0.4%
[278.581911] (kms_flip:2999) DEBUG: name = flip
last_ts = 278.894501
last_received_ts = 279.065643
last_seq = 10086
current_ts = 279.085083
current_received_ts = 279.256317
current_seq = 10096
count = 26
seq_step = 1
[278.581969] (kms_flip:2999) DEBUG: flip ts/seq: last 278.894501/10086, current 279.085083/10096: elapsed=190568.0us expected=191286.9us +- 956.4us, error 0.4%
[278.581978] (kms_flip:2999) DEBUG: name = vblank
last_ts = 279.066010
last_received_ts = 279.065582
last_seq = 10095
current_ts = 279.256561
current_received_ts = 279.256287
current_seq = 10105
count = 26
seq_step = 10
[278.581985] (kms_flip:2999) DEBUG: vblank ts/seq: last 279.066010/10095, current 279.256561/10105: elapsed=190533.0us expected=191286.9us +- 956.4us, error 0.4%
[278.771562] (kms_flip:2999) DEBUG: name = flip
last_ts = 279.085083
last_received_ts = 279.256317
last_seq = 10096
current_ts = 279.275604
current_received_ts = 279.446716
current_seq = 10106
count = 27
seq_step = 1
[278.771582] (kms_flip:2999) DEBUG: flip ts/seq: last 279.085083/10096, current 279.275604/10106: elapsed=190509.0us expected=191286.9us +- 956.4us, error 0.4%
[278.771590] (kms_flip:2999) DEBUG: name = vblank
last_ts = 279.256561
last_received_ts = 279.256287
last_seq = 10105
current_ts = 279.447021
current_received_ts = 279.446686
current_seq = 10115
count = 27
seq_step = 10
[278.771598] (kms_flip:2999) DEBUG: vblank ts/seq: last 279.256561/10105, current 279.447021/10115: elapsed=190471.0us expected=191286.9us +- 956.4us, error 0.4%
[278.961360] (kms_flip:2999) DEBUG: name = flip
last_ts = 279.275604
last_received_ts = 279.446716
last_seq = 10106
current_ts = 279.466095
current_received_ts = 279.637268
current_seq = 10116
count = 28
seq_step = 1
[278.961381] (kms_flip:2999) DEBUG: flip ts/seq: last 279.275604/10106, current 279.466095/10116: elapsed=190492.0us expected=191286.9us +- 956.4us, error 0.4%
[278.961388] (kms_flip:2999) DEBUG: name = vblank
last_ts = 279.447021
last_received_ts = 279.446686
last_seq = 10115
current_ts = 279.637512
current_received_ts = 279.637207
current_seq = 10125
count = 28
seq_step = 10
[278.961396] (kms_flip:2999) DEBUG: vblank ts/seq: last 279.447021/10115, current 279.637512/10125: elapsed=190490.0us expected=191286.9us +- 956.4us, error 0.4%
[279.150930] (kms_flip:2999) DEBUG: name = flip
last_ts = 279.466095
last_received_ts = 279.637268
last_seq = 10116
current_ts = 279.656555
current_received_ts = 279.827576
current_seq = 10126
count = 29
seq_step = 1
[279.150950] (kms_flip:2999) DEBUG: flip ts/seq: last 279.466095/10116, current 279.656555/10126: elapsed=190487.0us expected=191286.9us +- 956.4us, error 0.4%
[279.150958] (kms_flip:2999) DEBUG: name = vblank
last_ts = 279.637512
last_received_ts = 279.637207
last_seq = 10125
current_ts = 279.828003
current_received_ts = 279.827545
current_seq = 10135
count = 29
seq_step = 10
[279.150966] (kms_flip:2999) DEBUG: vblank ts/seq: last 279.637512/10125, current 279.828003/10135: elapsed=190482.0us expected=191286.9us +- 956.4us, error 0.4%
[279.340636] (kms_flip:2999) DEBUG: name = flip
last_ts = 279.656555
last_received_ts = 279.827576
last_seq = 10126
current_ts = 279.847046
current_received_ts = 280.018005
current_seq = 10136
count = 30
seq_step = 1
[279.340658] (kms_flip:2999) DEBUG: flip ts/seq: last 279.656555/10126, current 279.847046/10136: elapsed=190482.0us expected=191286.9us +- 956.4us, error 0.4%
[279.340667] (kms_flip:2999) DEBUG: name = vblank
last_ts = 279.828003
last_received_ts = 279.827545
last_seq = 10135
current_ts = 280.018494
current_received_ts = 280.017944
current_seq = 10145
count = 30
seq_step = 10
[279.340675] (kms_flip:2999) DEBUG: vblank ts/seq: last 279.828003/10135, current 280.018494/10145: elapsed=190482.0us expected=191286.9us +- 956.4us, error 0.4%
[279.530564] (kms_flip:2999) DEBUG: name = flip
last_ts = 279.847046
last_received_ts = 280.018005
last_seq = 10136
current_ts = 280.037537
current_received_ts = 280.208679
current_seq = 10146
count = 31
seq_step = 1
[279.530585] (kms_flip:2999) DEBUG: flip ts/seq: last 279.847046/10136, current 280.037537/10146: elapsed=190485.0us expected=191286.9us +- 956.4us, error 0.4%
[279.530592] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.018494
last_received_ts = 280.017944
last_seq = 10145
current_ts = 280.208954
current_received_ts = 280.208618
current_seq = 10155
count = 31
seq_step = 10
[279.530599] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.018494/10145, current 280.208954/10155: elapsed=190463.0us expected=191286.9us +- 956.4us, error 0.4%
[279.720346] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.037537
last_received_ts = 280.208679
last_seq = 10146
current_ts = 280.227966
current_received_ts = 280.399139
current_seq = 10156
count = 32
seq_step = 1
[279.720368] (kms_flip:2999) DEBUG: flip ts/seq: last 280.037537/10146, current 280.227966/10156: elapsed=190439.0us expected=191286.9us +- 956.4us, error 0.4%
[279.720376] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.208954
last_received_ts = 280.208618
last_seq = 10155
current_ts = 280.399323
current_received_ts = 280.399078
current_seq = 10165
count = 32
seq_step = 10
[279.720384] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.208954/10155, current 280.399323/10165: elapsed=190392.0us expected=191286.9us +- 956.4us, error 0.5%
[279.909962] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.227966
last_received_ts = 280.399139
last_seq = 10156
current_ts = 280.418365
current_received_ts = 280.589417
current_seq = 10166
count = 33
seq_step = 1
[279.909982] (kms_flip:2999) DEBUG: flip ts/seq: last 280.227966/10156, current 280.418365/10166: elapsed=190396.0us expected=191286.9us +- 956.4us, error 0.5%
[279.909990] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.399323
last_received_ts = 280.399078
last_seq = 10165
current_ts = 280.589752
current_received_ts = 280.589355
current_seq = 10175
count = 33
seq_step = 10
[279.909998] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.399323/10165, current 280.589752/10175: elapsed=190413.0us expected=191286.9us +- 956.4us, error 0.5%
[280.099647] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.418365
last_received_ts = 280.589417
last_seq = 10166
current_ts = 280.608765
current_received_ts = 280.779755
current_seq = 10176
count = 34
seq_step = 1
[280.099668] (kms_flip:2999) DEBUG: flip ts/seq: last 280.418365/10166, current 280.608765/10176: elapsed=190402.0us expected=191286.9us +- 956.4us, error 0.5%
[280.099676] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.589752
last_received_ts = 280.589355
last_seq = 10175
current_ts = 280.780151
current_received_ts = 280.779694
current_seq = 10185
count = 34
seq_step = 10
[280.099683] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.589752/10175, current 280.780151/10185: elapsed=190401.0us expected=191286.9us +- 956.4us, error 0.5%
[280.289552] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.608765
last_received_ts = 280.779755
last_seq = 10176
current_ts = 280.799194
current_received_ts = 280.970337
current_seq = 10186
count = 35
seq_step = 1
[280.289574] (kms_flip:2999) DEBUG: flip ts/seq: last 280.608765/10176, current 280.799194/10186: elapsed=190415.0us expected=191286.9us +- 956.4us, error 0.5%
[280.289582] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.780151
last_received_ts = 280.779694
last_seq = 10185
current_ts = 280.970551
current_received_ts = 280.970276
current_seq = 10195
count = 35
seq_step = 10
[280.289590] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.780151/10185, current 280.970551/10195: elapsed=190390.0us expected=191286.9us +- 956.4us, error 0.5%
[280.479218] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.799194
last_received_ts = 280.970337
last_seq = 10186
current_ts = 280.989594
current_received_ts = 281.160645
current_seq = 10196
count = 36
seq_step = 1
[280.479239] (kms_flip:2999) DEBUG: flip ts/seq: last 280.799194/10186, current 280.989594/10196: elapsed=190396.0us expected=191286.9us +- 956.4us, error 0.5%
[280.479248] (kms_flip:2999) DEBUG: name = vblank
last_ts = 280.970551
last_received_ts = 280.970276
last_seq = 10195
current_ts = 281.160950
current_received_ts = 281.160583
current_seq = 10205
count = 36
seq_step = 10
[280.479256] (kms_flip:2999) DEBUG: vblank ts/seq: last 280.970551/10195, current 281.160950/10205: elapsed=190398.0us expected=191286.9us +- 956.4us, error 0.5%
[280.669071] (kms_flip:2999) DEBUG: name = flip
last_ts = 280.989594
last_received_ts = 281.160645
last_seq = 10196
current_ts = 281.179962
current_received_ts = 281.351105
current_seq = 10206
count = 37
seq_step = 1
[280.669092] (kms_flip:2999) DEBUG: flip ts/seq: last 280.989594/10196, current 281.179962/10206: elapsed=190379.0us expected=191286.9us +- 956.4us, error 0.5%
[280.669100] (kms_flip:2999) DEBUG: name = vblank
last_ts = 281.160950
last_received_ts = 281.160583
last_seq = 10205
current_ts = 281.351257
current_received_ts = 281.350983
current_seq = 10215
count = 37
seq_step = 10
[280.669108] (kms_flip:2999) DEBUG: vblank ts/seq: last 281.160950/10205, current 281.351257/10215: elapsed=190330.0us expected=191286.9us +- 956.4us, error 0.5%
[280.669113] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 281.160950/10205, current 281.351257/10215: elapsed=190330.0us expected=191286.9us
[280.858662] (kms_flip:2999) DEBUG: name = flip
last_ts = 281.179962
last_received_ts = 281.351105
last_seq = 10206
current_ts = 281.370300
current_received_ts = 281.541290
current_seq = 10216
count = 38
seq_step = 1
[280.858683] (kms_flip:2999) DEBUG: flip ts/seq: last 281.179962/10206, current 281.370300/10216: elapsed=190336.0us expected=191286.9us +- 956.4us, error 0.5%
[280.858691] (kms_flip:2999) DEBUG: name = vblank
last_ts = 281.351257
last_received_ts = 281.350983
last_seq = 10215
current_ts = 281.541595
current_received_ts = 281.541229
current_seq = 10225
count = 38
seq_step = 10
[280.858700] (kms_flip:2999) DEBUG: vblank ts/seq: last 281.351257/10215, current 281.541595/10225: elapsed=190329.0us expected=191286.9us +- 956.4us, error 0.5%
[280.858705] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 281.351257/10215, current 281.541595/10225: elapsed=190329.0us expected=191286.9us
[281.048299] (kms_flip:2999) DEBUG: name = flip
last_ts = 281.370300
last_received_ts = 281.541290
last_seq = 10216
current_ts = 281.560638
current_received_ts = 281.731506
current_seq = 10226
count = 39
seq_step = 1
[281.048320] (kms_flip:2999) DEBUG: flip ts/seq: last 281.370300/10216, current 281.560638/10226: elapsed=190333.0us expected=191286.9us +- 956.4us, error 0.5%
[281.048328] (kms_flip:2999) DEBUG: name = vblank
last_ts = 281.541595
last_received_ts = 281.541229
last_seq = 10225
current_ts = 281.731934
current_received_ts = 281.731445
current_seq = 10235
count = 39
seq_step = 10
[281.048336] (kms_flip:2999) DEBUG: vblank ts/seq: last 281.541595/10225, current 281.731934/10235: elapsed=190336.0us expected=191286.9us +- 956.4us, error 0.5%
[281.238281] (kms_flip:2999) DEBUG: name = flip
last_ts = 281.560638
last_received_ts = 281.731506
last_seq = 10226
current_ts = 281.750946
current_received_ts = 281.922089
current_seq = 10236
count = 40
seq_step = 1
[281.238302] (kms_flip:2999) DEBUG: flip ts/seq: last 281.560638/10226, current 281.750946/10236: elapsed=190320.0us expected=191286.9us +- 956.4us, error 0.5%
[281.238309] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 281.560638/10226, current 281.750946/10236: elapsed=190320.0us expected=191286.9us
[281.238316] (kms_flip:2999) DEBUG: name = vblank
last_ts = 281.731934
last_received_ts = 281.731445
last_seq = 10235
current_ts = 281.922241
current_received_ts = 281.921997
current_seq = 10245
count = 40
seq_step = 10
[281.238323] (kms_flip:2999) DEBUG: vblank ts/seq: last 281.731934/10235, current 281.922241/10245: elapsed=190320.0us expected=191286.9us +- 956.4us, error 0.5%
[281.238328] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 281.731934/10235, current 281.922241/10245: elapsed=190320.0us expected=191286.9us
[281.427735] (kms_flip:2999) DEBUG: name = flip
last_ts = 281.750946
last_received_ts = 281.922089
last_seq = 10236
current_ts = 281.941284
current_received_ts = 282.112122
current_seq = 10246
count = 41
seq_step = 1
[281.427757] (kms_flip:2999) DEBUG: flip ts/seq: last 281.750946/10236, current 281.941284/10246: elapsed=190345.0us expected=191286.9us +- 956.4us, error 0.5%
[281.427765] (kms_flip:2999) DEBUG: name = vblank
last_ts = 281.922241
last_received_ts = 281.921997
last_seq = 10245
current_ts = 282.112579
current_received_ts = 282.112061
current_seq = 10255
count = 41
seq_step = 10
[281.427807] (kms_flip:2999) DEBUG: vblank ts/seq: last 281.922241/10245, current 282.112579/10255: elapsed=190333.0us expected=191286.9us +- 956.4us, error 0.5%
[281.617564] (kms_flip:2999) DEBUG: name = flip
last_ts = 281.941284
last_received_ts = 282.112122
last_seq = 10246
current_ts = 282.131622
current_received_ts = 282.302490
current_seq = 10256
count = 42
seq_step = 1
[281.617585] (kms_flip:2999) DEBUG: flip ts/seq: last 281.941284/10246, current 282.131622/10256: elapsed=190322.0us expected=191286.9us +- 956.4us, error 0.5%
[281.617591] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 281.941284/10246, current 282.131622/10256: elapsed=190322.0us expected=191286.9us
[281.617599] (kms_flip:2999) DEBUG: name = vblank
last_ts = 282.112579
last_received_ts = 282.112061
last_seq = 10255
current_ts = 282.302856
current_received_ts = 282.302429
current_seq = 10265
count = 42
seq_step = 10
[281.617606] (kms_flip:2999) DEBUG: vblank ts/seq: last 282.112579/10255, current 282.302856/10265: elapsed=190284.0us expected=191286.9us +- 956.4us, error 0.5%
[281.617611] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 282.112579/10255, current 282.302856/10265: elapsed=190284.0us expected=191286.9us
[281.807197] (kms_flip:2999) DEBUG: name = flip
last_ts = 282.131622
last_received_ts = 282.302490
last_seq = 10256
current_ts = 282.321899
current_received_ts = 282.492645
current_seq = 10266
count = 43
seq_step = 1
[281.807219] (kms_flip:2999) DEBUG: flip ts/seq: last 282.131622/10256, current 282.321899/10266: elapsed=190276.0us expected=191286.9us +- 956.4us, error 0.5%
[281.807226] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 282.131622/10256, current 282.321899/10266: elapsed=190276.0us expected=191286.9us
[281.807232] (kms_flip:2999) DEBUG: name = vblank
last_ts = 282.302856
last_received_ts = 282.302429
last_seq = 10265
current_ts = 282.493134
current_received_ts = 282.492615
current_seq = 10275
count = 43
seq_step = 10
[281.807240] (kms_flip:2999) DEBUG: vblank ts/seq: last 282.302856/10265, current 282.493134/10275: elapsed=190267.0us expected=191286.9us +- 956.4us, error 0.5%
[281.807245] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 282.302856/10265, current 282.493134/10275: elapsed=190267.0us expected=191286.9us
[281.997186] (kms_flip:2999) DEBUG: name = flip
last_ts = 282.321899
last_received_ts = 282.492645
last_seq = 10266
current_ts = 282.512146
current_received_ts = 282.683167
current_seq = 10276
count = 44
seq_step = 1
[281.997208] (kms_flip:2999) DEBUG: flip ts/seq: last 282.321899/10266, current 282.512146/10276: elapsed=190265.0us expected=191286.9us +- 956.4us, error 0.5%
[281.997215] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 282.321899/10266, current 282.512146/10276: elapsed=190265.0us expected=191286.9us
[281.997221] (kms_flip:2999) DEBUG: name = vblank
last_ts = 282.493134
last_received_ts = 282.492615
last_seq = 10275
current_ts = 282.683411
current_received_ts = 282.683136
current_seq = 10285
count = 44
seq_step = 10
[281.997229] (kms_flip:2999) DEBUG: vblank ts/seq: last 282.493134/10275, current 282.683411/10285: elapsed=190272.0us expected=191286.9us +- 956.4us, error 0.5%
[281.997234] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 282.493134/10275, current 282.683411/10285: elapsed=190272.0us expected=191286.9us
[282.186816] (kms_flip:2999) DEBUG: name = flip
last_ts = 282.512146
last_received_ts = 282.683167
last_seq = 10276
current_ts = 282.702423
current_received_ts = 282.873352
current_seq = 10286
count = 45
seq_step = 1
[282.186838] (kms_flip:2999) DEBUG: flip ts/seq: last 282.512146/10276, current 282.702423/10286: elapsed=190265.0us expected=191286.9us +- 956.4us, error 0.5%
[282.186845] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 282.512146/10276, current 282.702423/10286: elapsed=190265.0us expected=191286.9us
[282.186852] (kms_flip:2999) DEBUG: name = vblank
last_ts = 282.683411
last_received_ts = 282.683136
last_seq = 10285
current_ts = 282.873657
current_received_ts = 282.873291
current_seq = 10295
count = 45
seq_step = 10
[282.186859] (kms_flip:2999) DEBUG: vblank ts/seq: last 282.683411/10285, current 282.873657/10295: elapsed=190257.0us expected=191286.9us +- 956.4us, error 0.5%
[282.186865] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 282.683411/10285, current 282.873657/10295: elapsed=190257.0us expected=191286.9us
[282.376334] (kms_flip:2999) DEBUG: name = flip
last_ts = 282.702423
last_received_ts = 282.873352
last_seq = 10286
current_ts = 282.892700
current_received_ts = 283.063385
current_seq = 10296
count = 46
seq_step = 1
[282.376354] (kms_flip:2999) DEBUG: flip ts/seq: last 282.702423/10286, current 282.892700/10296: elapsed=190269.0us expected=191286.9us +- 956.4us, error 0.5%
[282.376361] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 282.702423/10286, current 282.892700/10296: elapsed=190269.0us expected=191286.9us
[282.376368] (kms_flip:2999) DEBUG: name = vblank
last_ts = 282.873657
last_received_ts = 282.873291
last_seq = 10295
current_ts = 283.063934
current_received_ts = 283.063324
current_seq = 10305
count = 46
seq_step = 10
[282.376375] (kms_flip:2999) DEBUG: vblank ts/seq: last 282.873657/10295, current 283.063934/10305: elapsed=190266.0us expected=191286.9us +- 956.4us, error 0.5%
[282.376380] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 282.873657/10295, current 283.063934/10305: elapsed=190266.0us expected=191286.9us
[282.566305] (kms_flip:2999) DEBUG: name = flip
last_ts = 282.892700
last_received_ts = 283.063385
last_seq = 10296
current_ts = 283.082977
current_received_ts = 283.253845
current_seq = 10306
count = 47
seq_step = 1
[282.566325] (kms_flip:2999) DEBUG: flip ts/seq: last 282.892700/10296, current 283.082977/10306: elapsed=190274.0us expected=191286.9us +- 956.4us, error 0.5%
[282.566332] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 282.892700/10296, current 283.082977/10306: elapsed=190274.0us expected=191286.9us
[282.566339] (kms_flip:2999) DEBUG: name = vblank
last_ts = 283.063934
last_received_ts = 283.063324
last_seq = 10305
current_ts = 283.254181
current_received_ts = 283.253815
current_seq = 10315
count = 47
seq_step = 10
[282.566347] (kms_flip:2999) DEBUG: vblank ts/seq: last 283.063934/10305, current 283.254181/10315: elapsed=190240.0us expected=191286.9us +- 956.4us, error 0.5%
[282.566352] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 283.063934/10305, current 283.254181/10315: elapsed=190240.0us expected=191286.9us
[282.756040] (kms_flip:2999) DEBUG: name = flip
last_ts = 283.082977
last_received_ts = 283.253845
last_seq = 10306
current_ts = 283.273193
current_received_ts = 283.444061
current_seq = 10316
count = 48
seq_step = 1
[282.756062] (kms_flip:2999) DEBUG: flip ts/seq: last 283.082977/10306, current 283.273193/10316: elapsed=190227.0us expected=191286.9us +- 956.4us, error 0.6%
[282.756069] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 283.082977/10306, current 283.273193/10316: elapsed=190227.0us expected=191286.9us
[282.756076] (kms_flip:2999) DEBUG: name = vblank
last_ts = 283.254181
last_received_ts = 283.253815
last_seq = 10315
current_ts = 283.444397
current_received_ts = 283.443970
current_seq = 10325
count = 48
seq_step = 10
[282.756084] (kms_flip:2999) DEBUG: vblank ts/seq: last 283.254181/10315, current 283.444397/10325: elapsed=190212.0us expected=191286.9us +- 956.4us, error 0.6%
[282.756089] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 283.254181/10315, current 283.444397/10325: elapsed=190212.0us expected=191286.9us
[282.945890] (kms_flip:2999) DEBUG: name = flip
last_ts = 283.273193
last_received_ts = 283.444061
last_seq = 10316
current_ts = 283.463409
current_received_ts = 283.634399
current_seq = 10326
count = 49
seq_step = 1
[282.945911] (kms_flip:2999) DEBUG: flip ts/seq: last 283.273193/10316, current 283.463409/10326: elapsed=190210.0us expected=191286.9us +- 956.4us, error 0.6%
[282.945918] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 283.273193/10316, current 283.463409/10326: elapsed=190210.0us expected=191286.9us
[282.945925] (kms_flip:2999) DEBUG: name = vblank
last_ts = 283.444397
last_received_ts = 283.443970
last_seq = 10325
current_ts = 283.634613
current_received_ts = 283.634338
current_seq = 10335
count = 49
seq_step = 10
[282.945932] (kms_flip:2999) DEBUG: vblank ts/seq: last 283.444397/10325, current 283.634613/10335: elapsed=190219.0us expected=191286.9us +- 956.4us, error 0.6%
[282.945937] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 283.444397/10325, current 283.634613/10335: elapsed=190219.0us expected=191286.9us
[283.135611] (kms_flip:2999) DEBUG: name = flip
last_ts = 283.463409
last_received_ts = 283.634399
last_seq = 10326
current_ts = 283.653625
current_received_ts = 283.824585
current_seq = 10336
count = 50
seq_step = 1
[283.135632] (kms_flip:2999) DEBUG: flip ts/seq: last 283.463409/10326, current 283.653625/10336: elapsed=190221.0us expected=191286.9us +- 956.4us, error 0.6%
[283.135639] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 283.463409/10326, current 283.653625/10336: elapsed=190221.0us expected=191286.9us
[283.135646] (kms_flip:2999) DEBUG: name = vblank
last_ts = 283.634613
last_received_ts = 283.634338
last_seq = 10335
current_ts = 283.824829
current_received_ts = 283.824524
current_seq = 10345
count = 50
seq_step = 10
[283.135653] (kms_flip:2999) DEBUG: vblank ts/seq: last 283.634613/10335, current 283.824829/10345: elapsed=190221.0us expected=191286.9us +- 956.4us, error 0.6%
[283.135658] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 283.634613/10335, current 283.824829/10345: elapsed=190221.0us expected=191286.9us
[283.325351] (kms_flip:2999) DEBUG: name = flip
last_ts = 283.653625
last_received_ts = 283.824585
last_seq = 10336
current_ts = 283.843842
current_received_ts = 284.014801
current_seq = 10346
count = 51
seq_step = 1
[283.325373] (kms_flip:2999) DEBUG: flip ts/seq: last 283.653625/10336, current 283.843842/10346: elapsed=190219.0us expected=191286.9us +- 956.4us, error 0.6%
[283.325380] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 283.653625/10336, current 283.843842/10346: elapsed=190219.0us expected=191286.9us
[283.325387] (kms_flip:2999) DEBUG: name = vblank
last_ts = 283.824829
last_received_ts = 283.824524
last_seq = 10345
current_ts = 284.015045
current_received_ts = 284.014740
current_seq = 10355
count = 51
seq_step = 10
[283.325394] (kms_flip:2999) DEBUG: vblank ts/seq: last 283.824829/10345, current 284.015045/10355: elapsed=190215.0us expected=191286.9us +- 956.4us, error 0.6%
[283.325399] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 283.824829/10345, current 284.015045/10355: elapsed=190215.0us expected=191286.9us
[283.515018] (kms_flip:2999) DEBUG: name = flip
last_ts = 283.843842
last_received_ts = 284.014801
last_seq = 10346
current_ts = 284.034058
current_received_ts = 284.204926
current_seq = 10356
count = 52
seq_step = 1
[283.515038] (kms_flip:2999) DEBUG: flip ts/seq: last 283.843842/10346, current 284.034058/10356: elapsed=190210.0us expected=191286.9us +- 956.4us, error 0.6%
[283.515045] (kms_flip:2999) DEBUG: inconsistent flip ts/seq: last 283.843842/10346, current 284.034058/10356: elapsed=190210.0us expected=191286.9us
[283.515052] (kms_flip:2999) DEBUG: name = vblank
last_ts = 284.015045
last_received_ts = 284.014740
last_seq = 10355
current_ts = 284.205231
current_received_ts = 284.204865
current_seq = 10365
count = 52
seq_step = 10
[283.515060] (kms_flip:2999) DEBUG: vblank ts/seq: last 284.015045/10355, current 284.205231/10365: elapsed=190191.0us expected=191286.9us +- 956.4us, error 0.6%
[283.515115] (kms_flip:2999) DEBUG: inconsistent vblank ts/seq: last 284.015045/10355, current 284.205231/10365: elapsed=190191.0us expected=191286.9us
[283.515121] (kms_flip:2999) INFO: Event flip: expected 528, counted 530, passrate = 77.36%, encoder type 2
[283.515213] (kms_flip:2999) DEBUG: dropped frames, expected 528, counted 530, passrate = 77.36%, encoder type 2
[283.515218] (kms_flip:2999) INFO: Event vblank: expected 528, counted 530, passrate = 73.58%, encoder type 2
[283.515255] (kms_flip:2999) DEBUG: dropped frames, expected 528, counted 530, passrate = 73.58%, encoder type 2
[283.515259] (kms_flip:2999) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
[283.515296] (kms_flip:2999) CRITICAL: Failed assertion: !retried
[283.554891] (kms_flip:2999) igt_core-INFO: Stack trace:
[283.572358] (kms_flip:2999) igt_core-INFO:   #0 ../lib/igt_core.c:2106 __igt_fail_assert()
[283.573810] (kms_flip:2999) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
[283.573922] (kms_flip:2999) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
[283.573996] (kms_flip:2999) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
[283.574061] (kms_flip:2999) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
[283.580220] (kms_flip:2999) igt_core-INFO:   #5 [__libc_init_first+0x8a]
[283.581355] (kms_flip:2999) igt_core-INFO:   #6 [__libc_start_main+0x8b]
[283.581649] (kms_flip:2999) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A2: FAIL (24.077s)
Dmesg
<6> [273.963182] Console: switching to colour dummy device 80x25
<6> [273.963575] [IGT] kms_flip: executing
<7> [273.965775] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [273.966449] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [273.966655] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [273.971569] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [273.971576] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [273.975617] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [273.976015] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [273.976019] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [273.980057] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [273.980312] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [273.980315] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [273.984308] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<7> [273.984561] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [273.984564] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [273.988583] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] disconnected
<7> [273.988595] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2]
<7> [273.988599] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [274.014284] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [274.014293] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [274.014296] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD monitor fitHeadless4k
<7> [274.014299] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:400:HDMI-A-2] ELD size 36, SAD count 0
<7> [274.014502] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [274.014630] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK on first message, retry
<7> [274.014939] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1)
<7> [274.015069] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [274.015436] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH)
<7> [274.015443] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [274.015448] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE)
<7> [274.015454] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2] probed modes:
<7> [274.015457] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [274.015459] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5
<7> [274.015462] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa
<7> [274.015464] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa
<7> [274.015467] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa
<7> [274.015469] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa
<7> [274.015472] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa
<7> [274.015474] 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> [274.015476] 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> [274.015479] 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> [274.015482] 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> [274.015484] 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> [274.015487] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa
<7> [274.015489] 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> [274.015491] 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> [274.015494] 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> [274.015496] 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> [274.015499] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [274.015501] 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> [274.015504] 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> [274.015506] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [274.015508] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa
<7> [274.015511] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6
<7> [274.015513] 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> [274.015516] 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> [274.015518] 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> [274.015521] 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> [274.015523] 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> [274.015525] 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> [274.015528] 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> [274.015530] 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> [274.015553] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [274.015556] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [274.019570] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [274.019583] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [274.019586] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [274.023589] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<6> [274.024217] [IGT] kms_flip: starting subtest flip-vs-blocking-wf-vblank
<6> [274.025922] [IGT] kms_flip: starting dynamic subtest A-HDMI-A2
<7> [274.026271] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:418]
<7> [274.026356] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:419]
<7> [274.092849] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [274.093017] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [274.093403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [274.093691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [274.093995] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [274.094274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [274.094554] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [274.094840] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [274.095117] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [274.095392] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [274.095666] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [274.095959] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [274.096204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [274.096418] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [274.096633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [274.096864] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [274.097077] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [274.097291] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [274.097505] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [274.097737] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [274.097949] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [274.098162] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [274.098375] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [274.098588] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [274.098822] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [274.099036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [274.099202] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [274.099366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [274.099532] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [274.099712] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [274.099878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [274.100043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [274.100209] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [274.100374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [274.100548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [274.100714] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [274.100878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [274.101043] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [274.101209] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [274.101376] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [274.101588] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [274.101757] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [274.101949] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [274.102114] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [274.102278] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [274.102414] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [274.102545] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [274.102676] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [274.102822] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [274.102959] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [274.103093] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [274.103240] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [274.103396] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [274.103533] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [274.103668] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [274.103801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [274.103934] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [274.104066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [274.104199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [274.104335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [274.115954] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [274.135920] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [274.136567] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [274.137313] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [274.137918] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [274.138513] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [274.139130] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [274.139775] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [274.140331] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [274.140915] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [274.141421] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [274.142015] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [274.142757] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [274.143326] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [274.143920] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [274.145154] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [274.145517] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [274.145828] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [274.145882] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [274.146029] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [274.146461] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [274.146804] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [274.147186] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [274.147535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [274.147904] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [274.148261] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [274.148553] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [274.148829] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [274.149132] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [274.149408] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [274.149689] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [274.149951] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [274.150239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [274.150501] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [274.150761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [274.151033] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [274.151295] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [274.151555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [274.151784] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [274.151990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [274.152182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [274.152374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [274.152578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [274.152765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [274.152962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [274.153148] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [274.153334] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [274.153520] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [274.153706] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [274.153919] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [274.154104] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [274.154290] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [274.154476] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [274.154646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [274.154781] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [274.154931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [274.155067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [274.155215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [274.155345] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [274.155475] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [274.155604] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [274.155769] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [274.155905] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [274.156059] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [274.156191] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [274.156322] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [274.156452] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [274.156580] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [274.156715] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [274.156861] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [274.156995] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [274.157128] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [274.157273] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [274.157422] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [274.157558] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [274.157712] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [274.157852] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [274.157989] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [274.158122] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [274.158254] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [274.158386] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [274.158519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [274.158714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [274.158846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [274.158978] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [274.159108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [274.159237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [274.159369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [274.159498] 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> [274.159638] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [274.159769] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [274.159900] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [274.160030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [274.160159] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [274.160289] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [274.160417] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [274.160556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [274.160686] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [274.160815] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [274.160945] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [274.161073] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [274.161202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, 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> [274.161331] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [274.161472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [274.161601] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [274.161729] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.161858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.161987] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.162114] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [274.162242] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [274.162371] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.162511] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.162639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [274.162768] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [274.162897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:418] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [274.163027] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [274.163156] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [274.163284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [274.163421] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [274.163550] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [274.163680] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [274.163808] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [274.164605] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [274.164771] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [274.164911] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [274.165048] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [274.165185] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [274.165334] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [274.165471] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [274.165650] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [274.165871] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [274.166007] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [274.166197] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [274.166388] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [274.185778] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [274.185949] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [274.204882] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [274.205129] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [287.407165] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [287.407451] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [287.408158] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [287.408746] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [287.409360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [287.409945] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [287.410499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [287.411083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [287.411631] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0)
<7> [287.412234] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [287.412761] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0)
<7> [287.412892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [287.413022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0)
<7> [287.413154] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [287.413284] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [287.413414] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [287.413543] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [287.413673] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [287.413811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [287.413942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [287.414071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0)
<7> [287.414200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [287.414330] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0)
<7> [287.414460] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [287.414589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0)
<7> [287.414719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [287.414861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [287.414990] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [287.415118] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [287.415248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [287.415376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [287.415504] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [287.415633] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [287.415771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [287.415902] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [287.416032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0)
<7> [287.416161] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0)
<7> [287.416289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 139929, found 0)
<7> [287.416419] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [287.416550] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [287.416707] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [287.416851] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [287.417003] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [287.417133] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [287.417263] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [287.417392] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [287.417521] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [287.417650] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [287.417803] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [287.417939] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [287.418072] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [287.418219] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [287.418367] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [287.418502] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [287.418635] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [287.418776] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [287.418908] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [287.419039] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [287.419170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [287.419300] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [287.430048] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [287.448614] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [287.449304] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [287.450041] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [287.450679] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [287.451264] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [287.451876] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [287.452511] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [287.453069] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [287.453650] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [287.454196] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [287.454779] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [287.455598] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [287.456226] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [287.456811] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [287.458075] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [287.458515] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [287.458906] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [287.458958] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:400:HDMI-A-2]
<7> [287.459097] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:400:HDMI-A-2] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [287.459540] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [287.459905] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [287.460297] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:399:DDI TC1/PHY C] [CRTC:151:pipe A]
<7> [287.460675] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [287.461040] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [287.461430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [287.461833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [287.462145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [287.462478] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [287.462783] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [287.463092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360)
<7> [287.463333] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [287.463597] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360)
<7> [287.463838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [287.464080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096)
<7> [287.464320] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [287.464583] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [287.464824] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [287.465056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [287.465271] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [287.465507] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [287.465722] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [287.465950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360)
<7> [287.466126] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [287.466300] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360)
<7> [287.466559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [287.466736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096)
<7> [287.466912] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [287.467088] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [287.467262] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [287.467453] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [287.467630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [287.467806] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [287.467982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [287.468145] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [287.468285] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [287.468438] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [287.468577] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929)
<7> [287.468716] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929)
<7> [287.468867] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 139929)
<7> [287.468998] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [287.469148] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [287.469280] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [287.469447] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [287.469576] i915 0000:00:02.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [287.469706] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [287.469836] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0
<7> [287.469967] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0
<7> [287.470098] i915 0000:00:02.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0
<7> [287.470242] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [287.470388] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [287.470522] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [287.470669] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [287.470816] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [287.470950] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [287.471087] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [287.471225] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [287.471369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [287.471503] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [287.471636] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [287.471767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [287.471899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [287.472030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [287.472161] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [287.472297] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [287.472431] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [287.472561] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 42, guardband: 44 vsync start: 0, vsync end: 0
<7> [287.472691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [287.472822] 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> [287.472952] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [287.473083] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5
<7> [287.473212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [287.473353] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5
<7> [287.473483] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [287.473612] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pixel rate 139929, min cdclk 69965, min voltage level 0
<7> [287.473741] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [287.473870] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [287.473999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [287.474127] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [287.474262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [287.474391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [287.474519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, cfgcr1: 0xe84, 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> [287.474649] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [287.474777] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [287.474906] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [287.475035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.475164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.475307] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.475435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [287.475563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [287.475691] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.475819] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.475948] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [287.476076] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [287.476203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:418] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [287.476344] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [287.476471] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [287.476599] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [287.476727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [287.476855] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [287.476983] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [287.477111] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [287.477325] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [287.477474] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [287.477610] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [287.477744] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [287.477878] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [287.478010] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [287.478143] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [287.478329] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [287.478549] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [287.478684] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [287.478873] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [287.479046] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [287.498634] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [287.498810] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [287.518052] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [287.518815] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<6> [298.103247] [IGT] kms_flip: finished subtest A-HDMI-A2, FAIL
Created at 2026-05-23 22:33:39