Results for igt@kms_flip@wf_vblank-ts-check@a-hdmi-a1

Result: Fail

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

DetailValue
Duration 24.48 seconds
Hostname 
shard-rkl-2
Igt-Version 
IGT-Version: 2.4-gd28bd0b9e (x86_64) (Linux: 7.1.0-rc3-CI_DRM_18467-gf8ee23694aa6+ x86_64)
Out 
Using IGT_SRANDOM=1778524218 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 18974us; measured 19139.4us +- 7.957us accuracy 0.12%
vblank interval differs from modeline! expected 18973.8us, measured 19139us +- 7.957us, difference 165.6us (20.8 sigma)
Event vblank: expected 528, counted 530, passrate = 69.81%, encoder type 2
Expected frametime: 18974us; measured 19002.6us +- 10.689us accuracy 0.17%
vblank interval differs from modeline! expected 18973.8us, measured 19003us +- 10.689us, difference 28.8us (2.7 sigma)
Event vblank: expected 531, counted 540, passrate = 7.41%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
  #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (24.479s)
Err 
Starting subtest: wf_vblank-ts-check
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2507) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2507) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2507) DEBUG: vblank ts/seq: last 276.495789/8713, current 276.686493/8723: elapsed=190705.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 276.686493
last_received_ts = 276.686249
last_seq = 8723
current_ts = 276.877136
current_received_ts = 276.876740
current_seq = 8733
count = 23
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 276.686493/8723, current 276.877136/8733: elapsed=190629.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 276.877136
last_received_ts = 276.876740
last_seq = 8733
current_ts = 277.067749
current_received_ts = 277.067383
current_seq = 8743
count = 24
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 276.877136/8733, current 277.067749/8743: elapsed=190625.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 277.067749
last_received_ts = 277.067383
last_seq = 8743
current_ts = 277.258392
current_received_ts = 277.258148
current_seq = 8753
count = 25
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 277.067749/8743, current 277.258392/8753: elapsed=190632.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 277.258392
last_received_ts = 277.258148
last_seq = 8753
current_ts = 277.449036
current_received_ts = 277.448761
current_seq = 8763
count = 26
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 277.258392/8753, current 277.449036/8763: elapsed=190644.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 277.449036
last_received_ts = 277.448761
last_seq = 8763
current_ts = 277.639648
current_received_ts = 277.639374
current_seq = 8773
count = 27
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 277.449036/8763, current 277.639648/8773: elapsed=190613.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 277.639648
last_received_ts = 277.639374
last_seq = 8773
current_ts = 277.830170
current_received_ts = 277.829803
current_seq = 8783
count = 28
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 277.639648/8773, current 277.830170/8783: elapsed=190530.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 277.830170
last_received_ts = 277.829803
last_seq = 8783
current_ts = 278.020721
current_received_ts = 278.020477
current_seq = 8793
count = 29
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 277.830170/8783, current 278.020721/8793: elapsed=190532.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.020721
last_received_ts = 278.020477
last_seq = 8793
current_ts = 278.211243
current_received_ts = 278.211060
current_seq = 8803
count = 30
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.020721/8793, current 278.211243/8803: elapsed=190534.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.211243
last_received_ts = 278.211060
last_seq = 8803
current_ts = 278.401794
current_received_ts = 278.401520
current_seq = 8813
count = 31
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.211243/8803, current 278.401794/8813: elapsed=190541.0us expected=191393.8us +- 957.0us, error 0.4%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.401794
last_received_ts = 278.401520
last_seq = 8813
current_ts = 278.592316
current_received_ts = 278.591858
current_seq = 8823
count = 32
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.401794/8813, current 278.592316/8823: elapsed=190524.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.592316
last_received_ts = 278.591858
last_seq = 8823
current_ts = 278.782776
current_received_ts = 278.782318
current_seq = 8833
count = 33
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.592316/8823, current 278.782776/8833: elapsed=190457.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.782776
last_received_ts = 278.782318
last_seq = 8833
current_ts = 278.973206
current_received_ts = 278.972839
current_seq = 8843
count = 34
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.782776/8833, current 278.973206/8843: elapsed=190434.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 278.782776/8833, current 278.973206/8843: elapsed=190434.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 278.973206
last_received_ts = 278.972839
last_seq = 8843
current_ts = 279.163635
current_received_ts = 279.163330
current_seq = 8853
count = 35
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 278.973206/8843, current 279.163635/8853: elapsed=190446.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 279.163635
last_received_ts = 279.163330
last_seq = 8853
current_ts = 279.354095
current_received_ts = 279.353851
current_seq = 8863
count = 36
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 279.163635/8853, current 279.354095/8863: elapsed=190458.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 279.354095
last_received_ts = 279.353851
last_seq = 8863
current_ts = 279.544556
current_received_ts = 279.544098
current_seq = 8873
count = 37
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 279.354095/8863, current 279.544556/8873: elapsed=190441.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 279.544556
last_received_ts = 279.544098
last_seq = 8873
current_ts = 279.734955
current_received_ts = 279.734680
current_seq = 8883
count = 38
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 279.544556/8873, current 279.734955/8883: elapsed=190403.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 279.544556/8873, current 279.734955/8883: elapsed=190403.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 279.734955
last_received_ts = 279.734680
last_seq = 8883
current_ts = 279.925293
current_received_ts = 279.925079
current_seq = 8893
count = 39
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 279.734955/8883, current 279.925293/8893: elapsed=190360.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 279.734955/8883, current 279.925293/8893: elapsed=190360.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 279.925293
last_received_ts = 279.925079
last_seq = 8893
current_ts = 280.115692
current_received_ts = 280.115326
current_seq = 8903
count = 40
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 279.925293/8893, current 280.115692/8903: elapsed=190373.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 279.925293/8893, current 280.115692/8903: elapsed=190373.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 280.115692
last_received_ts = 280.115326
last_seq = 8903
current_ts = 280.306061
current_received_ts = 280.305786
current_seq = 8913
count = 41
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 280.115692/8903, current 280.306061/8913: elapsed=190372.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 280.115692/8903, current 280.306061/8913: elapsed=190372.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 280.306061
last_received_ts = 280.305786
last_seq = 8913
current_ts = 280.496399
current_received_ts = 280.496094
current_seq = 8923
count = 42
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 280.306061/8913, current 280.496399/8923: elapsed=190355.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 280.306061/8913, current 280.496399/8923: elapsed=190355.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 280.496399
last_received_ts = 280.496094
last_seq = 8923
current_ts = 280.686768
current_received_ts = 280.686493
current_seq = 8933
count = 43
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 280.496399/8923, current 280.686768/8933: elapsed=190352.0us expected=191393.8us +- 957.0us, error 0.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 280.496399/8923, current 280.686768/8933: elapsed=190352.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 280.686768
last_received_ts = 280.686493
last_seq = 8933
current_ts = 280.877045
current_received_ts = 280.876801
current_seq = 8943
count = 44
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 280.686768/8933, current 280.877045/8943: elapsed=190301.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 280.686768/8933, current 280.877045/8943: elapsed=190301.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 280.877045
last_received_ts = 280.876801
last_seq = 8943
current_ts = 281.067352
current_received_ts = 281.067139
current_seq = 8953
count = 45
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 280.877045/8943, current 281.067352/8953: elapsed=190293.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 280.877045/8943, current 281.067352/8953: elapsed=190293.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 281.067352
last_received_ts = 281.067139
last_seq = 8953
current_ts = 281.257660
current_received_ts = 281.257385
current_seq = 8963
count = 46
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 281.067352/8953, current 281.257660/8963: elapsed=190305.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 281.067352/8953, current 281.257660/8963: elapsed=190305.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 281.257660
last_received_ts = 281.257385
last_seq = 8963
current_ts = 281.447968
current_received_ts = 281.447571
current_seq = 8973
count = 47
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 281.257660/8963, current 281.447968/8973: elapsed=190301.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 281.257660/8963, current 281.447968/8973: elapsed=190301.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 281.447968
last_received_ts = 281.447571
last_seq = 8973
current_ts = 281.638245
current_received_ts = 281.638000
current_seq = 8983
count = 48
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 281.447968/8973, current 281.638245/8983: elapsed=190286.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 281.447968/8973, current 281.638245/8983: elapsed=190286.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 281.638245
last_received_ts = 281.638000
last_seq = 8983
current_ts = 281.828491
current_received_ts = 281.828156
current_seq = 8993
count = 49
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 281.638245/8983, current 281.828491/8993: elapsed=190240.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 281.638245/8983, current 281.828491/8993: elapsed=190240.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 281.828491
last_received_ts = 281.828156
last_seq = 8993
current_ts = 282.018738
current_received_ts = 282.018463
current_seq = 9003
count = 50
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 281.828491/8993, current 282.018738/9003: elapsed=190256.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 281.828491/8993, current 282.018738/9003: elapsed=190256.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 282.018738
last_received_ts = 282.018463
last_seq = 9003
current_ts = 282.208984
current_received_ts = 282.208374
current_seq = 9013
count = 51
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 282.018738/9003, current 282.208984/9013: elapsed=190244.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 282.018738/9003, current 282.208984/9013: elapsed=190244.0us expected=191393.8us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 282.208984
last_received_ts = 282.208374
last_seq = 9013
current_ts = 282.399231
current_received_ts = 282.398804
current_seq = 9023
count = 52
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 282.208984/9013, current 282.399231/9023: elapsed=190235.0us expected=191393.8us +- 957.0us, error 0.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 282.208984/9013, current 282.399231/9023: elapsed=190235.0us expected=191393.8us
(kms_flip:2507) INFO: Event vblank: expected 528, counted 530, passrate = 69.81%, encoder type 2
(kms_flip:2507) DEBUG: dropped frames, expected 528, counted 530, passrate = 69.81%, encoder type 2
(kms_flip:2507) DEBUG: Retrying without a hotplug event
(kms_flip:2507) DEBUG: No stale events found
(kms_flip:2507) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2507) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2507) INFO: Expected frametime: 18974us; measured 19002.6us +- 10.689us accuracy 0.17%
(kms_flip:2507) INFO: vblank interval differs from modeline! expected 18973.8us, measured 19003us +- 10.689us, difference 28.8us (2.7 sigma)
(kms_flip:2507) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 286.066223
current_received_ts = 286.065765
current_seq = 9214
count = 0
seq_step = 10
(kms_flip:2507) DEBUG: name = vblank
last_ts = 286.066223
last_received_ts = 286.065765
last_seq = 9214
current_ts = 286.256104
current_received_ts = 286.255646
current_seq = 9224
count = 1
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 286.066223/9214, current 286.256104/9224: elapsed=189888.0us expected=190025.6us +- 950.1us, error 0.1%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 286.256104
last_received_ts = 286.255646
last_seq = 9224
current_ts = 286.445984
current_received_ts = 286.445740
current_seq = 9234
count = 2
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 286.256104/9224, current 286.445984/9234: elapsed=189874.0us expected=190025.6us +- 950.1us, error 0.1%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 286.445984
last_received_ts = 286.445740
last_seq = 9234
current_ts = 286.635437
current_received_ts = 286.635162
current_seq = 9244
count = 3
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 286.445984/9234, current 286.635437/9244: elapsed=189431.0us expected=190025.6us +- 950.1us, error 0.3%
(kms_flip:2507) DEBUG: name = vblank
last_ts = 286.635437
last_received_ts = 286.635162
last_seq = 9244
current_ts = 286.820648
current_received_ts = 286.820221
current_seq = 9254
count = 4
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 286.635437/9244, current 286.820648/9254: elapsed=185234.0us expected=190025.6us +- 950.1us, error 2.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 286.635437/9244, current 286.820648/9254: elapsed=185234.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 286.820648
last_received_ts = 286.820221
last_seq = 9254
current_ts = 287.005890
current_received_ts = 287.005310
current_seq = 9264
count = 5
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 286.820648/9254, current 287.005890/9264: elapsed=185242.0us expected=190025.6us +- 950.1us, error 2.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 286.820648/9254, current 287.005890/9264: elapsed=185242.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.005890
last_received_ts = 287.005310
last_seq = 9264
current_ts = 287.191132
current_received_ts = 287.190887
current_seq = 9274
count = 6
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.005890/9264, current 287.191132/9274: elapsed=185232.0us expected=190025.6us +- 950.1us, error 2.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.005890/9264, current 287.191132/9274: elapsed=185232.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.191132
last_received_ts = 287.190887
last_seq = 9274
current_ts = 287.376373
current_received_ts = 287.376038
current_seq = 9284
count = 7
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.191132/9274, current 287.376373/9284: elapsed=185252.0us expected=190025.6us +- 950.1us, error 2.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.191132/9274, current 287.376373/9284: elapsed=185252.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.376373
last_received_ts = 287.376038
last_seq = 9284
current_ts = 287.561615
current_received_ts = 287.561371
current_seq = 9294
count = 8
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.376373/9284, current 287.561615/9294: elapsed=185243.0us expected=190025.6us +- 950.1us, error 2.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.376373/9284, current 287.561615/9294: elapsed=185243.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.561615
last_received_ts = 287.561371
last_seq = 9294
current_ts = 287.747284
current_received_ts = 287.747070
current_seq = 9304
count = 9
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.561615/9294, current 287.747284/9304: elapsed=185655.0us expected=190025.6us +- 950.1us, error 2.3%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.561615/9294, current 287.747284/9304: elapsed=185655.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.747284
last_received_ts = 287.747070
last_seq = 9304
current_ts = 287.933075
current_received_ts = 287.932709
current_seq = 9314
count = 10
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.747284/9304, current 287.933075/9314: elapsed=185785.0us expected=190025.6us +- 950.1us, error 2.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.747284/9304, current 287.933075/9314: elapsed=185785.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 287.933075
last_received_ts = 287.932709
last_seq = 9314
current_ts = 288.118866
current_received_ts = 288.118591
current_seq = 9324
count = 11
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 287.933075/9314, current 288.118866/9324: elapsed=185787.0us expected=190025.6us +- 950.1us, error 2.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 287.933075/9314, current 288.118866/9324: elapsed=185787.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 288.118866
last_received_ts = 288.118591
last_seq = 9324
current_ts = 288.304657
current_received_ts = 288.304382
current_seq = 9334
count = 12
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 288.118866/9324, current 288.304657/9334: elapsed=185798.0us expected=190025.6us +- 950.1us, error 2.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 288.118866/9324, current 288.304657/9334: elapsed=185798.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 288.304657
last_received_ts = 288.304382
last_seq = 9334
current_ts = 288.490448
current_received_ts = 288.490082
current_seq = 9344
count = 13
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 288.304657/9334, current 288.490448/9344: elapsed=185782.0us expected=190025.6us +- 950.1us, error 2.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 288.304657/9334, current 288.490448/9344: elapsed=185782.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 288.490448
last_received_ts = 288.490082
last_seq = 9344
current_ts = 288.676392
current_received_ts = 288.675995
current_seq = 9354
count = 14
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 288.490448/9344, current 288.676392/9354: elapsed=185950.0us expected=190025.6us +- 950.1us, error 2.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 288.490448/9344, current 288.676392/9354: elapsed=185950.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 288.676392
last_received_ts = 288.675995
last_seq = 9354
current_ts = 288.862640
current_received_ts = 288.862396
current_seq = 9364
count = 15
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 288.676392/9354, current 288.862640/9364: elapsed=186255.0us expected=190025.6us +- 950.1us, error 2.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 288.676392/9354, current 288.862640/9364: elapsed=186255.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 288.862640
last_received_ts = 288.862396
last_seq = 9364
current_ts = 289.048920
current_received_ts = 289.048645
current_seq = 9374
count = 16
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 288.862640/9364, current 289.048920/9374: elapsed=186285.0us expected=190025.6us +- 950.1us, error 2.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 288.862640/9364, current 289.048920/9374: elapsed=186285.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.048920
last_received_ts = 289.048645
last_seq = 9374
current_ts = 289.235199
current_received_ts = 289.234833
current_seq = 9384
count = 17
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.048920/9374, current 289.235199/9384: elapsed=186265.0us expected=190025.6us +- 950.1us, error 2.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.048920/9374, current 289.235199/9384: elapsed=186265.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.235199
last_received_ts = 289.234833
last_seq = 9384
current_ts = 289.421448
current_received_ts = 289.421143
current_seq = 9394
count = 18
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.235199/9384, current 289.421448/9394: elapsed=186272.0us expected=190025.6us +- 950.1us, error 2.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.235199/9384, current 289.421448/9394: elapsed=186272.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.421448
last_received_ts = 289.421143
last_seq = 9394
current_ts = 289.607727
current_received_ts = 289.607483
current_seq = 9404
count = 19
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.421448/9394, current 289.607727/9404: elapsed=186280.0us expected=190025.6us +- 950.1us, error 2.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.421448/9394, current 289.607727/9404: elapsed=186280.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.607727
last_received_ts = 289.607483
last_seq = 9404
current_ts = 289.794434
current_received_ts = 289.794159
current_seq = 9414
count = 20
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.607727/9404, current 289.794434/9414: elapsed=186695.0us expected=190025.6us +- 950.1us, error 1.8%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.607727/9404, current 289.794434/9414: elapsed=186695.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.794434
last_received_ts = 289.794159
last_seq = 9414
current_ts = 289.981110
current_received_ts = 289.980896
current_seq = 9424
count = 21
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.794434/9414, current 289.981110/9424: elapsed=186688.0us expected=190025.6us +- 950.1us, error 1.8%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.794434/9414, current 289.981110/9424: elapsed=186688.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 289.981110
last_received_ts = 289.980896
last_seq = 9424
current_ts = 290.167816
current_received_ts = 290.167450
current_seq = 9434
count = 22
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 289.981110/9424, current 290.167816/9434: elapsed=186699.0us expected=190025.6us +- 950.1us, error 1.8%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 289.981110/9424, current 290.167816/9434: elapsed=186699.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 290.167816
last_received_ts = 290.167450
last_seq = 9434
current_ts = 290.354492
current_received_ts = 290.354248
current_seq = 9444
count = 23
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 290.167816/9434, current 290.354492/9444: elapsed=186685.0us expected=190025.6us +- 950.1us, error 1.8%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 290.167816/9434, current 290.354492/9444: elapsed=186685.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 290.354492
last_received_ts = 290.354248
last_seq = 9444
current_ts = 290.541199
current_received_ts = 290.540833
current_seq = 9454
count = 24
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 290.354492/9444, current 290.541199/9454: elapsed=186702.0us expected=190025.6us +- 950.1us, error 1.7%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 290.354492/9444, current 290.541199/9454: elapsed=186702.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 290.541199
last_received_ts = 290.540833
last_seq = 9454
current_ts = 290.728119
current_received_ts = 290.727875
current_seq = 9464
count = 25
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 290.541199/9454, current 290.728119/9464: elapsed=186912.0us expected=190025.6us +- 950.1us, error 1.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 290.541199/9454, current 290.728119/9464: elapsed=186912.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 290.728119
last_received_ts = 290.727875
last_seq = 9464
current_ts = 290.915192
current_received_ts = 290.914734
current_seq = 9474
count = 26
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 290.728119/9464, current 290.915192/9474: elapsed=187057.0us expected=190025.6us +- 950.1us, error 1.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 290.728119/9464, current 290.915192/9474: elapsed=187057.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 290.915192
last_received_ts = 290.914734
last_seq = 9474
current_ts = 291.102264
current_received_ts = 291.101990
current_seq = 9484
count = 27
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 290.915192/9474, current 291.102264/9484: elapsed=187072.0us expected=190025.6us +- 950.1us, error 1.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 290.915192/9474, current 291.102264/9484: elapsed=187072.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 291.102264
last_received_ts = 291.101990
last_seq = 9484
current_ts = 291.289307
current_received_ts = 291.288788
current_seq = 9494
count = 28
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 291.102264/9484, current 291.289307/9494: elapsed=187063.0us expected=190025.6us +- 950.1us, error 1.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 291.102264/9484, current 291.289307/9494: elapsed=187063.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 291.289307
last_received_ts = 291.288788
last_seq = 9494
current_ts = 291.476379
current_received_ts = 291.476135
current_seq = 9504
count = 29
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 291.289307/9494, current 291.476379/9504: elapsed=187058.0us expected=190025.6us +- 950.1us, error 1.6%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 291.289307/9494, current 291.476379/9504: elapsed=187058.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 291.476379
last_received_ts = 291.476135
last_seq = 9504
current_ts = 291.663544
current_received_ts = 291.663269
current_seq = 9514
count = 30
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 291.476379/9504, current 291.663544/9514: elapsed=187160.0us expected=190025.6us +- 950.1us, error 1.5%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 291.476379/9504, current 291.663544/9514: elapsed=187160.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 291.663544
last_received_ts = 291.663269
last_seq = 9514
current_ts = 291.850922
current_received_ts = 291.850647
current_seq = 9524
count = 31
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 291.663544/9514, current 291.850922/9524: elapsed=187379.0us expected=190025.6us +- 950.1us, error 1.4%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 291.663544/9514, current 291.850922/9524: elapsed=187379.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 291.850922
last_received_ts = 291.850647
last_seq = 9524
current_ts = 292.038300
current_received_ts = 292.038086
current_seq = 9534
count = 32
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 291.850922/9524, current 292.038300/9534: elapsed=187388.0us expected=190025.6us +- 950.1us, error 1.4%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 291.850922/9524, current 292.038300/9534: elapsed=187388.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.038300
last_received_ts = 292.038086
last_seq = 9534
current_ts = 292.225677
current_received_ts = 292.225433
current_seq = 9544
count = 33
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.038300/9534, current 292.225677/9544: elapsed=187382.0us expected=190025.6us +- 950.1us, error 1.4%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.038300/9534, current 292.225677/9544: elapsed=187382.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.225677
last_received_ts = 292.225433
last_seq = 9544
current_ts = 292.413086
current_received_ts = 292.412689
current_seq = 9554
count = 34
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.225677/9544, current 292.413086/9554: elapsed=187396.0us expected=190025.6us +- 950.1us, error 1.4%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.225677/9544, current 292.413086/9554: elapsed=187396.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.413086
last_received_ts = 292.412689
last_seq = 9554
current_ts = 292.600433
current_received_ts = 292.600128
current_seq = 9564
count = 35
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.413086/9554, current 292.600433/9564: elapsed=187370.0us expected=190025.6us +- 950.1us, error 1.4%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.413086/9554, current 292.600433/9564: elapsed=187370.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.600433
last_received_ts = 292.600128
last_seq = 9564
current_ts = 292.788116
current_received_ts = 292.787872
current_seq = 9574
count = 36
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.600433/9564, current 292.788116/9574: elapsed=187671.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.600433/9564, current 292.788116/9574: elapsed=187671.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.788116
last_received_ts = 292.787872
last_seq = 9574
current_ts = 292.975800
current_received_ts = 292.975525
current_seq = 9584
count = 37
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.788116/9574, current 292.975800/9584: elapsed=187669.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.788116/9574, current 292.975800/9584: elapsed=187669.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 292.975800
last_received_ts = 292.975525
last_seq = 9584
current_ts = 293.163452
current_received_ts = 293.163208
current_seq = 9594
count = 38
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 292.975800/9584, current 293.163452/9594: elapsed=187658.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 292.975800/9584, current 293.163452/9594: elapsed=187658.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 293.163452
last_received_ts = 293.163208
last_seq = 9594
current_ts = 293.351135
current_received_ts = 293.350708
current_seq = 9604
count = 39
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 293.163452/9594, current 293.351135/9604: elapsed=187678.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 293.163452/9594, current 293.351135/9604: elapsed=187678.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 293.351135
last_received_ts = 293.350708
last_seq = 9604
current_ts = 293.538788
current_received_ts = 293.538513
current_seq = 9614
count = 40
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 293.351135/9604, current 293.538788/9614: elapsed=187666.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 293.351135/9604, current 293.538788/9614: elapsed=187666.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 293.538788
last_received_ts = 293.538513
last_seq = 9614
current_ts = 293.726593
current_received_ts = 293.726349
current_seq = 9624
count = 41
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 293.538788/9614, current 293.726593/9624: elapsed=187813.0us expected=190025.6us +- 950.1us, error 1.2%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 293.538788/9614, current 293.726593/9624: elapsed=187813.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 293.726593
last_received_ts = 293.726349
last_seq = 9624
current_ts = 293.914520
current_received_ts = 293.914185
current_seq = 9634
count = 42
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 293.726593/9624, current 293.914520/9634: elapsed=187916.0us expected=190025.6us +- 950.1us, error 1.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 293.726593/9624, current 293.914520/9634: elapsed=187916.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 293.914520
last_received_ts = 293.914185
last_seq = 9634
current_ts = 294.102448
current_received_ts = 294.101990
current_seq = 9644
count = 43
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 293.914520/9634, current 294.102448/9644: elapsed=187917.0us expected=190025.6us +- 950.1us, error 1.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 293.914520/9634, current 294.102448/9644: elapsed=187917.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 294.102448
last_received_ts = 294.101990
last_seq = 9644
current_ts = 294.290344
current_received_ts = 294.289917
current_seq = 9654
count = 44
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 294.102448/9644, current 294.290344/9654: elapsed=187901.0us expected=190025.6us +- 950.1us, error 1.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 294.102448/9644, current 294.290344/9654: elapsed=187901.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 294.290344
last_received_ts = 294.289917
last_seq = 9654
current_ts = 294.478271
current_received_ts = 294.477997
current_seq = 9664
count = 45
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 294.290344/9654, current 294.478271/9664: elapsed=187923.0us expected=190025.6us +- 950.1us, error 1.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 294.290344/9654, current 294.478271/9664: elapsed=187923.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 294.478271
last_received_ts = 294.477997
last_seq = 9664
current_ts = 294.666260
current_received_ts = 294.665985
current_seq = 9674
count = 46
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 294.478271/9664, current 294.666260/9674: elapsed=187989.0us expected=190025.6us +- 950.1us, error 1.1%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 294.478271/9664, current 294.666260/9674: elapsed=187989.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 294.666260
last_received_ts = 294.665985
last_seq = 9674
current_ts = 294.854370
current_received_ts = 294.854126
current_seq = 9684
count = 47
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 294.666260/9674, current 294.854370/9684: elapsed=188130.0us expected=190025.6us +- 950.1us, error 1.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 294.666260/9674, current 294.854370/9684: elapsed=188130.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 294.854370
last_received_ts = 294.854126
last_seq = 9684
current_ts = 295.042511
current_received_ts = 295.042236
current_seq = 9694
count = 48
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 294.854370/9684, current 295.042511/9694: elapsed=188120.0us expected=190025.6us +- 950.1us, error 1.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 294.854370/9684, current 295.042511/9694: elapsed=188120.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 295.042511
last_received_ts = 295.042236
last_seq = 9694
current_ts = 295.230621
current_received_ts = 295.230255
current_seq = 9704
count = 49
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 295.042511/9694, current 295.230621/9704: elapsed=188129.0us expected=190025.6us +- 950.1us, error 1.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 295.042511/9694, current 295.230621/9704: elapsed=188129.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 295.230621
last_received_ts = 295.230255
last_seq = 9704
current_ts = 295.418762
current_received_ts = 295.418304
current_seq = 9714
count = 50
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 295.230621/9704, current 295.418762/9714: elapsed=188143.0us expected=190025.6us +- 950.1us, error 1.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 295.230621/9704, current 295.418762/9714: elapsed=188143.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 295.418762
last_received_ts = 295.418304
last_seq = 9714
current_ts = 295.606873
current_received_ts = 295.606628
current_seq = 9724
count = 51
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 295.418762/9714, current 295.606873/9724: elapsed=188118.0us expected=190025.6us +- 950.1us, error 1.0%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 295.418762/9714, current 295.606873/9724: elapsed=188118.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 295.606873
last_received_ts = 295.606628
last_seq = 9724
current_ts = 295.795227
current_received_ts = 295.794983
current_seq = 9734
count = 52
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 295.606873/9724, current 295.795227/9734: elapsed=188329.0us expected=190025.6us +- 950.1us, error 0.9%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 295.606873/9724, current 295.795227/9734: elapsed=188329.0us expected=190025.6us
(kms_flip:2507) DEBUG: name = vblank
last_ts = 295.795227
last_received_ts = 295.794983
last_seq = 9734
current_ts = 295.983521
current_received_ts = 295.983276
current_seq = 9744
count = 53
seq_step = 10
(kms_flip:2507) DEBUG: vblank ts/seq: last 295.795227/9734, current 295.983521/9744: elapsed=188318.0us expected=190025.6us +- 950.1us, error 0.9%
(kms_flip:2507) DEBUG: inconsistent vblank ts/seq: last 295.795227/9734, current 295.983521/9744: elapsed=188318.0us expected=190025.6us
(kms_flip:2507) INFO: Event vblank: expected 531, counted 540, passrate = 7.41%, encoder type 2
(kms_flip:2507) DEBUG: dropped frames, expected 531, counted 540, passrate = 7.41%, encoder type 2
(kms_flip:2507) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2507) CRITICAL: Failed assertion: !retried
(kms_flip:2507) igt_core-INFO: Stack trace:
(kms_flip:2507) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2507) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:2507) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2507) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:2507) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2507) igt_core-INFO:   #5 ../sysdeps/nptl/libc_start_call_main.h:74 __libc_start_call_main()
(kms_flip:2507) igt_core-INFO:   #6 ../csu/libc-start.c:128 __libc_start_main@@GLIBC_2.34()
(kms_flip:2507) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (24.479s)
Dmesg
<6> [317.377916] Console: switching to colour dummy device 80x25
<6> [317.378293] [IGT] kms_flip: executing
<7> [317.380390] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [317.380976] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [317.381194] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [317.386198] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2]
<7> [317.386205] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [317.390405] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2] disconnected
<7> [317.390688] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [317.390693] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [317.394844] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [317.395091] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [317.395094] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [317.399262] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<7> [317.399530] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1]
<7> [317.399537] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [317.429231] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:390:HDMI-A-1] Supported Monitor Refresh rate range is 0 Hz - 0 Hz
<7> [317.429240] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:390:HDMI-A-1] Assigning EDID-1.4 digital sink color depth as 8 bpc.
<7> [317.429243] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:390:HDMI-A-1] ELD monitor fitHeadless4k
<7> [317.429246] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:390:HDMI-A-1] ELD size 36, SAD count 0
<7> [317.431583] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: DP-HDMI ADAPTOR\004 (err 0)
<7> [317.434057] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode adaptor ID: a0 (err 0)
<7> [317.437917] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] DP dual mode adaptor (type 2 HDMI) detected (max TMDS clock: 600000 kHz)
<7> [317.438047] i915 0000:00:02.0: [drm:intel_hdmi_set_edid [i915]] Ignoring DP dual mode adaptor max TMDS clock for native HDMI port
<7> [317.438531] 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> [317.438538] 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> [317.438543] 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> [317.438549] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:390:HDMI-A-1] probed modes:
<7> [317.438552] 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> [317.438556] 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> [317.438558] 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> [317.438561] 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> [317.438564] 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> [317.438567] 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> [317.438570] 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> [317.438572] 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> [317.438575] 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> [317.438578] 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> [317.438581] 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> [317.438583] 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> [317.438586] 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> [317.438589] 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> [317.438592] 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> [317.438595] 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> [317.438598] 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> [317.438601] 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> [317.438603] 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> [317.438606] 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> [317.438609] 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> [317.438612] 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> [317.438614] 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> [317.438617] 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> [317.438620] 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> [317.438623] 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> [317.438625] 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> [317.438628] 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> [317.438631] 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> [317.438634] 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> [317.438636] 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> [317.438661] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2]
<7> [317.438665] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:400:HDMI-A-2]
<7> [317.442826] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:400:HDMI-A-2] disconnected
<7> [317.442839] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1]
<7> [317.442842] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:404:DP-1]
<7> [317.447006] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:404:DP-1] disconnected
<7> [317.447018] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3]
<7> [317.447021] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:412:HDMI-A-3]
<7> [317.451193] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:412:HDMI-A-3] disconnected
<6> [317.451814] [IGT] kms_flip: starting subtest wf_vblank-ts-check
<6> [317.453335] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [317.453654] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:417]
<7> [317.453734] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:437]
<7> [317.496024] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [317.496149] 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> [317.496382] 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> [317.496635] 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> [317.496856] 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> [317.497056] 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> [317.497255] 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> [317.497452] 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> [317.497663] 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> [317.497859] 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> [317.498054] 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> [317.498248] 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> [317.498441] 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> [317.498659] 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> [317.498837] 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> [317.499012] 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> [317.499190] 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> [317.499365] 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> [317.499550] 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> [317.499725] 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> [317.499900] 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> [317.500077] 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> [317.500252] 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> [317.500429] 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> [317.500620] 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> [317.500796] 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> [317.500972] 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> [317.501149] 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> [317.501325] 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> [317.501502] 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> [317.501670] 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> [317.501807] 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> [317.501943] 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> [317.502079] 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> [317.502216] 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> [317.502352] 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> [317.502488] 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> [317.502638] 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> [317.502775] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [317.502911] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [317.503071] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [317.503210] 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> [317.503366] 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> [317.503507] 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> [317.503644] 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> [317.503780] 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> [317.503915] 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> [317.504051] 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> [317.504205] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [317.504348] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [317.504505] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [317.504653] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [317.504802] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [317.504938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [317.505073] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [317.505207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [317.505340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [317.505473] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [317.505618] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [317.505749] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [317.505926] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [317.506064] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [317.508593] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [317.527439] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [317.527790] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [317.532866] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [317.533229] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [317.533599] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [317.533926] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [317.534283] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [317.534572] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [317.534808] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [317.535041] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [317.535293] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [317.535527] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [317.535878] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [317.536199] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [317.536869] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [317.537087] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [317.537292] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [317.537326] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:390:HDMI-A-1]
<7> [317.537430] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:390:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [317.537620] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [317.537785] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [317.537965] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:389:DDI B/PHY B] [CRTC:151:pipe A]
<7> [317.538135] 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> [317.538337] 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> [317.538514] 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> [317.538696] 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> [317.538872] 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> [317.539047] 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> [317.539234] 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> [317.539407] 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> [317.539581] 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> [317.539753] 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> [317.539926] 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> [317.540109] 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> [317.540295] 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> [317.540468] 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> [317.540646] 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> [317.540776] 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> [317.540908] 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> [317.541039] 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> [317.541175] 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> [317.541308] 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> [317.541439] 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> [317.541570] 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> [317.541700] 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> [317.541830] 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> [317.541962] 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> [317.542093] 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> [317.542233] 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> [317.542364] 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> [317.542493] 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> [317.542624] 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> [317.542755] 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> [317.542885] 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> [317.543016] 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> [317.543147] 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> [317.543290] 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> [317.543421] 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> [317.543552] 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> [317.543683] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [317.543832] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [317.543963] 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> [317.544120] 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> [317.544274] 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> [317.544404] 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> [317.544533] 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> [317.544662] 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> [317.544795] 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> [317.544943] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [317.545080] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [317.545223] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [317.545369] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [317.545517] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [317.545651] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [317.545788] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [317.545925] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [317.546060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [317.546203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [317.546336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [317.546467] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [317.546600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [317.546733] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [317.546865] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [317.546997] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [317.547135] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [317.547265] 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> [317.547397] 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> [317.547527] 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> [317.547657] 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> [317.547788] 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> [317.547918] 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> [317.548049] 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> [317.548228] 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> [317.548359] 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> [317.548489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [317.548618] 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> [317.548748] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [317.548877] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [317.549008] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [317.549153] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [317.549283] 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> [317.549414] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [317.549542] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [317.549672] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [317.549801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.549931] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.550060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.550202] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [317.550330] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [317.550460] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.550588] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.550717] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [317.550846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [317.550974] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:417] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [317.551109] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [317.551239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [317.551369] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [317.551498] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [317.551628] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [317.551757] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [317.551886] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [317.552508] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [317.552649] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [317.552784] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [317.552919] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [317.553053] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [317.553205] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [317.553373] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [317.553596] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [317.553732] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [317.553910] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [317.558767] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [317.558942] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [317.578491] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [317.578692] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [317.597946] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [317.598597] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [317.617700] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [317.618350] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [317.619027] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [317.923077] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [317.923707] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [317.956775] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [317.957427] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [317.958101] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [331.012161] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [331.012455] 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> [331.013209] 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> [331.013830] 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> [331.014399] 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> [331.015041] 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> [331.015598] 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> [331.016197] 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> [331.016770] 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> [331.017320] 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> [331.017900] 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> [331.018032] 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> [331.018164] 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> [331.018295] 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> [331.018427] 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> [331.018556] 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> [331.018687] 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> [331.018827] 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> [331.018957] 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> [331.019089] 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> [331.019220] 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> [331.019350] 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> [331.019481] 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> [331.019612] 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> [331.019748] 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> [331.019878] 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> [331.020008] 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> [331.020137] 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> [331.020266] 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> [331.020395] 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> [331.020525] 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> [331.020655] 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> [331.020794] 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> [331.020924] 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> [331.021054] 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> [331.021184] 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> [331.021314] 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> [331.021445] 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> [331.021575] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [331.021707] i915 0000:00:02.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:151:pipe A] releasing DPLL 0
<7> [331.021879] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [331.022012] 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> [331.022165] 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> [331.022295] 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> [331.022426] 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> [331.022555] 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> [331.022688] 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> [331.022832] 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> [331.022980] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 0 num active planes 0
<7> [331.023117] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [331.023251] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [331.023398] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 69965 kHz -> 0 kHz
<7> [331.023547] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 8746 kHz -> 0 kHz
<7> [331.023684] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: no [modeset]
<7> [331.023830] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [331.023965] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [331.024096] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [331.024228] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [331.024360] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [331.024491] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [331.024667] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [331.025358] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [331.030865] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [331.049830] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_B
<7> [331.050377] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Disabling DP dual mode adaptor TMDS output
<7> [331.055751] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:151:pipe A]
<7> [331.056263] i915 0000:00:02.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [331.056755] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [331.057218] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [331.057722] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [331.058165] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [331.058605] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [331.059083] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [331.059456] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [331.059807] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [331.060270] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [331.060704] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [331.061582] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe B]
<7> [331.061924] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe C]
<7> [331.062191] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [331.062227] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:390:HDMI-A-1]
<7> [331.062319] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:390:HDMI-A-1] Limiting target display pipe bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [331.062582] i915 0000:00:02.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [331.062846] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [331.063100] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:389:DDI B/PHY B] [CRTC:151:pipe A]
<7> [331.063344] 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> [331.063594] 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> [331.063871] 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> [331.064121] 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> [331.064370] 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> [331.064614] 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> [331.064880] 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> [331.065139] 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> [331.065365] 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> [331.065537] 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> [331.065727] 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> [331.065900] 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> [331.066078] 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> [331.066249] 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> [331.066421] 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> [331.066592] 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> [331.066796] 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> [331.066982] 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> [331.067154] 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> [331.067326] 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> [331.067498] 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> [331.067680] 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> [331.067852] 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> [331.068025] 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> [331.068197] 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> [331.068355] 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> [331.068485] 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> [331.068615] 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> [331.068758] 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> [331.068888] 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> [331.069018] 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> [331.069148] 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> [331.069277] 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> [331.069407] 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> [331.069537] 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> [331.069676] 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> [331.069807] 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> [331.069938] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [331.070087] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [331.070221] 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> [331.070374] 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> [331.070504] 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> [331.070701] 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> [331.070830] 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> [331.070960] 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> [331.071089] 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> [331.071232] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 559716 num active planes 1
<7> [331.071366] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [331.071499] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [331.071651] i915 0000:00:02.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 69965 kHz
<7> [331.071797] i915 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 8746 kHz
<7> [331.071931] i915 0000:00:02.0: [drm:intel_find_dpll [i915]] [CRTC:151:pipe A] allocated DPLL 0
<7> [331.072067] i915 0000:00:02.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:151:pipe A] reserving DPLL 0
<7> [331.072205] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [331.072340] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [331.072474] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [331.072606] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [331.072751] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [331.072883] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [331.073015] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [331.073150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [331.073285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [331.073418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [331.073551] 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> [331.073697] 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> [331.073829] 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> [331.073961] 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> [331.074092] 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> [331.074223] 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> [331.074354] 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> [331.074485] 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> [331.074623] 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> [331.074753] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [331.074883] 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> [331.075013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [331.075142] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [331.075271] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [331.075399] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [331.075528] 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> [331.075669] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [331.075797] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [331.075926] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [331.076056] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.076185] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.076315] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.076443] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [331.076572] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [331.076710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.076838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.076967] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [331.077096] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [331.077224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:417] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [331.077354] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [331.077483] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [331.077619] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [331.077747] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [331.077876] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [331.078005] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [331.078134] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [331.078341] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:389:DDI B/PHY B]
<7> [331.078477] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:399:DDI TC1/PHY C]
<7> [331.078621] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:403:DDI TC2/PHY D]
<7> [331.078756] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:405:DP-MST A]
<7> [331.078890] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:406:DP-MST B]
<7> [331.079023] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:407:DP-MST C]
<7> [331.079192] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [331.079412] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:151:pipe A]
<7> [331.079548] i915 0000:00:02.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [331.079736] i915 0000:00:02.0: [drm:intel_dp_dual_mode_set_tmds_output [i915]] Enabling DP dual mode adaptor TMDS output
<7> [331.084596] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_B
<7> [331.084791] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [331.104244] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [331.104438] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [331.123754] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:390:HDMI-A-1]
<7> [331.124579] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [331.143712] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [331.144380] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [331.145065] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [331.447203] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [331.447836] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [331.482169] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [331.482827] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [331.483488] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<6> [331.592532] pcieport 0000:00:06.0: AER: Correctable error message received from 0000:01:00.0
<4> [331.592553] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [331.592560] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [331.592568] nvme 0000:01:00.0: [ 0] RxErr (First)
<6> [331.701521] pcieport 0000:00:06.0: AER: Correctable error message received from 0000:01:00.0
<4> [331.701542] nvme 0000:01:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)
<4> [331.701549] nvme 0000:01:00.0: device [15b7:5006] error status/mask=00000001/0000e000
<4> [331.701556] nvme 0000:01:00.0: [ 0] RxErr (First)
<6> [341.932268] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-05-11 20:57:25