Results for igt@kms_flip@blocking-wf_vblank@a-hdmi-a3

Result: Fail

git-log-oneline i915_display_info7 igt_runner7 runtimes7 results7.json results7-i915-load.json guc_logs7.tar i915_display_info_post_exec7 boot7 dmesg7

DetailValue
Duration 19.09 seconds
Hostname 
shard-dg1-12
Igt-Version 
IGT-Version: 2.4-g8a9a4e37b (x86_64) (Linux: 7.1.0-rc2-CI_DRM_18448-ge9b21b152e77+ x86_64)
Out 
Using IGT_SRANDOM=1778233207 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 17371.9us +- 27.593us accuracy 0.48%
vblank interval differs from modeline! expected 16666.7us, measured 17372us +- 27.593us, difference 705.3us (25.6 sigma)
Event vblank: expected 432, counted 440, passrate = 11.36%, encoder type 2
Expected frametime: 16667us; measured 16839.0us +- 1.966us accuracy 0.04%
vblank interval differs from modeline! expected 16666.7us, measured 16839us +- 1.966us, difference 172.3us (87.6 sigma)
Event vblank: expected 448, counted 450, passrate = 66.67%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A3: FAIL (19.085s)
Err 
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A3
(kms_flip:7718) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:7718) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:7718) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:7718) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:7718) INFO: Expected frametime: 16667us; measured 17371.9us +- 27.593us accuracy 0.48%
(kms_flip:7718) INFO: vblank interval differs from modeline! expected 16666.7us, measured 17372us +- 27.593us, difference 705.3us (25.6 sigma)
(kms_flip:7718) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 518.382080
current_received_ts = 518.381592
current_seq = 15802
count = 0
seq_step = 10
(kms_flip:7718) DEBUG: name = vblank
last_ts = 518.382080
last_received_ts = 518.381592
last_seq = 15802
current_ts = 518.555054
current_received_ts = 518.554504
current_seq = 15812
count = 1
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 518.382080/15802, current 518.555054/15812: elapsed=172944.0us expected=173719.4us +- 868.6us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 518.555054
last_received_ts = 518.554504
last_seq = 15812
current_ts = 518.727966
current_received_ts = 518.727478
current_seq = 15822
count = 2
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 518.555054/15812, current 518.727966/15822: elapsed=172934.0us expected=173719.4us +- 868.6us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 518.727966
last_received_ts = 518.727478
last_seq = 15822
current_ts = 518.900879
current_received_ts = 518.900391
current_seq = 15832
count = 3
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 518.727966/15822, current 518.900879/15832: elapsed=172928.0us expected=173719.4us +- 868.6us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 518.900879
last_received_ts = 518.900391
last_seq = 15832
current_ts = 519.073853
current_received_ts = 519.073303
current_seq = 15842
count = 4
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 518.900879/15832, current 519.073853/15842: elapsed=172940.0us expected=173719.4us +- 868.6us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.073853
last_received_ts = 519.073303
last_seq = 15842
current_ts = 519.246399
current_received_ts = 519.245850
current_seq = 15852
count = 5
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.073853/15842, current 519.246399/15852: elapsed=172549.0us expected=173719.4us +- 868.6us, error 0.7%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.073853/15842, current 519.246399/15852: elapsed=172549.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.246399
last_received_ts = 519.245850
last_seq = 15852
current_ts = 519.418579
current_received_ts = 519.418030
current_seq = 15862
count = 6
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.246399/15852, current 519.418579/15862: elapsed=172155.0us expected=173719.4us +- 868.6us, error 0.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.246399/15852, current 519.418579/15862: elapsed=172155.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.418579
last_received_ts = 519.418030
last_seq = 15862
current_ts = 519.590698
current_received_ts = 519.590210
current_seq = 15872
count = 7
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.418579/15862, current 519.590698/15872: elapsed=172169.0us expected=173719.4us +- 868.6us, error 0.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.418579/15862, current 519.590698/15872: elapsed=172169.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.590698
last_received_ts = 519.590210
last_seq = 15872
current_ts = 519.762878
current_received_ts = 519.762390
current_seq = 15882
count = 8
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.590698/15872, current 519.762878/15882: elapsed=172162.0us expected=173719.4us +- 868.6us, error 0.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.590698/15872, current 519.762878/15882: elapsed=172162.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.762878
last_received_ts = 519.762390
last_seq = 15882
current_ts = 519.935059
current_received_ts = 519.934509
current_seq = 15892
count = 9
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.762878/15882, current 519.935059/15892: elapsed=172162.0us expected=173719.4us +- 868.6us, error 0.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.762878/15882, current 519.935059/15892: elapsed=172162.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 519.935059
last_received_ts = 519.934509
last_seq = 15892
current_ts = 520.107178
current_received_ts = 520.106689
current_seq = 15902
count = 10
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 519.935059/15892, current 520.107178/15902: elapsed=172162.0us expected=173719.4us +- 868.6us, error 0.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 519.935059/15892, current 520.107178/15902: elapsed=172162.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.107178
last_received_ts = 520.106689
last_seq = 15902
current_ts = 520.278870
current_received_ts = 520.278381
current_seq = 15912
count = 11
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.107178/15902, current 520.278870/15912: elapsed=171693.0us expected=173719.4us +- 868.6us, error 1.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.107178/15902, current 520.278870/15912: elapsed=171693.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.278870
last_received_ts = 520.278381
last_seq = 15912
current_ts = 520.450378
current_received_ts = 520.449890
current_seq = 15922
count = 12
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.278870/15912, current 520.450378/15922: elapsed=171486.0us expected=173719.4us +- 868.6us, error 1.3%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.278870/15912, current 520.450378/15922: elapsed=171486.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.450378
last_received_ts = 520.449890
last_seq = 15922
current_ts = 520.621887
current_received_ts = 520.621338
current_seq = 15932
count = 13
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.450378/15922, current 520.621887/15932: elapsed=171486.0us expected=173719.4us +- 868.6us, error 1.3%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.450378/15922, current 520.621887/15932: elapsed=171486.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.621887
last_received_ts = 520.621338
last_seq = 15932
current_ts = 520.793335
current_received_ts = 520.792847
current_seq = 15942
count = 14
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.621887/15932, current 520.793335/15942: elapsed=171486.0us expected=173719.4us +- 868.6us, error 1.3%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.621887/15932, current 520.793335/15942: elapsed=171486.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.793335
last_received_ts = 520.792847
last_seq = 15942
current_ts = 520.964844
current_received_ts = 520.964355
current_seq = 15952
count = 15
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.793335/15942, current 520.964844/15952: elapsed=171487.0us expected=173719.4us +- 868.6us, error 1.3%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.793335/15942, current 520.964844/15952: elapsed=171487.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 520.964844
last_received_ts = 520.964355
last_seq = 15952
current_ts = 521.136353
current_received_ts = 521.135803
current_seq = 15962
count = 16
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 520.964844/15952, current 521.136353/15962: elapsed=171485.0us expected=173719.4us +- 868.6us, error 1.3%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 520.964844/15952, current 521.136353/15962: elapsed=171485.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.136353
last_received_ts = 521.135803
last_seq = 15962
current_ts = 521.307373
current_received_ts = 521.306824
current_seq = 15972
count = 17
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.136353/15962, current 521.307373/15972: elapsed=171017.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.136353/15962, current 521.307373/15972: elapsed=171017.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.307373
last_received_ts = 521.306824
last_seq = 15972
current_ts = 521.478210
current_received_ts = 521.477722
current_seq = 15982
count = 18
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.307373/15972, current 521.478210/15982: elapsed=170892.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.307373/15972, current 521.478210/15982: elapsed=170892.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.478210
last_received_ts = 521.477722
last_seq = 15982
current_ts = 521.649109
current_received_ts = 521.648621
current_seq = 15992
count = 19
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.478210/15982, current 521.649109/15992: elapsed=170895.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.478210/15982, current 521.649109/15992: elapsed=170895.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.649109
last_received_ts = 521.648621
last_seq = 15992
current_ts = 521.820007
current_received_ts = 521.819519
current_seq = 16002
count = 20
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.649109/15992, current 521.820007/16002: elapsed=170895.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.649109/15992, current 521.820007/16002: elapsed=170895.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.820007
last_received_ts = 521.819519
last_seq = 16002
current_ts = 521.990906
current_received_ts = 521.990417
current_seq = 16012
count = 21
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.820007/16002, current 521.990906/16012: elapsed=170895.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.820007/16002, current 521.990906/16012: elapsed=170895.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 521.990906
last_received_ts = 521.990417
last_seq = 16012
current_ts = 522.161804
current_received_ts = 522.161316
current_seq = 16022
count = 22
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 521.990906/16012, current 522.161804/16022: elapsed=170896.0us expected=173719.4us +- 868.6us, error 1.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 521.990906/16012, current 522.161804/16022: elapsed=170896.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 522.161804
last_received_ts = 522.161316
last_seq = 16022
current_ts = 522.332214
current_received_ts = 522.331665
current_seq = 16032
count = 23
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 522.161804/16022, current 522.332214/16032: elapsed=170377.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 522.161804/16022, current 522.332214/16032: elapsed=170377.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 522.332214
last_received_ts = 522.331665
last_seq = 16032
current_ts = 522.502563
current_received_ts = 522.502075
current_seq = 16042
count = 24
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 522.332214/16032, current 522.502563/16042: elapsed=170379.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 522.332214/16032, current 522.502563/16042: elapsed=170379.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 522.502563
last_received_ts = 522.502075
last_seq = 16042
current_ts = 522.672974
current_received_ts = 522.672424
current_seq = 16052
count = 25
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 522.502563/16042, current 522.672974/16052: elapsed=170376.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 522.502563/16042, current 522.672974/16052: elapsed=170376.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 522.672974
last_received_ts = 522.672424
last_seq = 16052
current_ts = 522.843323
current_received_ts = 522.842834
current_seq = 16062
count = 26
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 522.672974/16052, current 522.843323/16062: elapsed=170378.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 522.672974/16052, current 522.843323/16062: elapsed=170378.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 522.843323
last_received_ts = 522.842834
last_seq = 16062
current_ts = 523.013733
current_received_ts = 523.013184
current_seq = 16072
count = 27
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 522.843323/16062, current 523.013733/16072: elapsed=170376.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 522.843323/16062, current 523.013733/16072: elapsed=170376.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.013733
last_received_ts = 523.013184
last_seq = 16072
current_ts = 523.184021
current_received_ts = 523.183533
current_seq = 16082
count = 28
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.013733/16072, current 523.184021/16082: elapsed=170333.0us expected=173719.4us +- 868.6us, error 1.9%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.013733/16072, current 523.184021/16082: elapsed=170333.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.184021
last_received_ts = 523.183533
last_seq = 16082
current_ts = 523.353943
current_received_ts = 523.353394
current_seq = 16092
count = 29
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.184021/16082, current 523.353943/16092: elapsed=169922.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.184021/16082, current 523.353943/16092: elapsed=169922.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.353943
last_received_ts = 523.353394
last_seq = 16092
current_ts = 523.523865
current_received_ts = 523.523376
current_seq = 16102
count = 30
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.353943/16092, current 523.523865/16102: elapsed=169929.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.353943/16092, current 523.523865/16102: elapsed=169929.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.523865
last_received_ts = 523.523376
last_seq = 16102
current_ts = 523.693787
current_received_ts = 523.693298
current_seq = 16112
count = 31
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.523865/16102, current 523.693787/16112: elapsed=169925.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.523865/16102, current 523.693787/16112: elapsed=169925.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.693787
last_received_ts = 523.693298
last_seq = 16112
current_ts = 523.863770
current_received_ts = 523.863220
current_seq = 16122
count = 32
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.693787/16112, current 523.863770/16122: elapsed=169926.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.693787/16112, current 523.863770/16122: elapsed=169926.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 523.863770
last_received_ts = 523.863220
last_seq = 16122
current_ts = 524.033691
current_received_ts = 524.033142
current_seq = 16132
count = 33
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 523.863770/16122, current 524.033691/16132: elapsed=169923.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 523.863770/16122, current 524.033691/16132: elapsed=169923.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.033691
last_received_ts = 524.033142
last_seq = 16132
current_ts = 524.203491
current_received_ts = 524.203003
current_seq = 16142
count = 34
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.033691/16132, current 524.203491/16142: elapsed=169850.0us expected=173719.4us +- 868.6us, error 2.2%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.033691/16132, current 524.203491/16142: elapsed=169850.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.203491
last_received_ts = 524.203003
last_seq = 16142
current_ts = 524.373047
current_received_ts = 524.372498
current_seq = 16152
count = 35
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.203491/16142, current 524.373047/16152: elapsed=169525.0us expected=173719.4us +- 868.6us, error 2.4%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.203491/16142, current 524.373047/16152: elapsed=169525.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.373047
last_received_ts = 524.372498
last_seq = 16152
current_ts = 524.542542
current_received_ts = 524.542053
current_seq = 16162
count = 36
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.373047/16152, current 524.542542/16162: elapsed=169523.0us expected=173719.4us +- 868.6us, error 2.4%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.373047/16152, current 524.542542/16162: elapsed=169523.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.542542
last_received_ts = 524.542053
last_seq = 16162
current_ts = 524.712097
current_received_ts = 524.711548
current_seq = 16172
count = 37
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.542542/16162, current 524.712097/16172: elapsed=169535.0us expected=173719.4us +- 868.6us, error 2.4%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.542542/16162, current 524.712097/16172: elapsed=169535.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.712097
last_received_ts = 524.711548
last_seq = 16172
current_ts = 524.881653
current_received_ts = 524.881104
current_seq = 16182
count = 38
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.712097/16172, current 524.881653/16182: elapsed=169529.0us expected=173719.4us +- 868.6us, error 2.4%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.712097/16172, current 524.881653/16182: elapsed=169529.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 524.881653
last_received_ts = 524.881104
last_seq = 16182
current_ts = 525.051147
current_received_ts = 525.050659
current_seq = 16192
count = 39
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 524.881653/16182, current 525.051147/16192: elapsed=169528.0us expected=173719.4us +- 868.6us, error 2.4%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 524.881653/16182, current 525.051147/16192: elapsed=169528.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 525.051147
last_received_ts = 525.050659
last_seq = 16192
current_ts = 525.220581
current_received_ts = 525.220093
current_seq = 16202
count = 40
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 525.051147/16192, current 525.220581/16202: elapsed=169425.0us expected=173719.4us +- 868.6us, error 2.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 525.051147/16192, current 525.220581/16202: elapsed=169425.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 525.220581
last_received_ts = 525.220093
last_seq = 16202
current_ts = 525.389771
current_received_ts = 525.389221
current_seq = 16212
count = 41
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 525.220581/16202, current 525.389771/16212: elapsed=169182.0us expected=173719.4us +- 868.6us, error 2.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 525.220581/16202, current 525.389771/16212: elapsed=169182.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 525.389771
last_received_ts = 525.389221
last_seq = 16212
current_ts = 525.558960
current_received_ts = 525.558411
current_seq = 16222
count = 42
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 525.389771/16212, current 525.558960/16222: elapsed=169183.0us expected=173719.4us +- 868.6us, error 2.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 525.389771/16212, current 525.558960/16222: elapsed=169183.0us expected=173719.4us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 525.558960
last_received_ts = 525.558411
last_seq = 16222
current_ts = 525.728149
current_received_ts = 525.727600
current_seq = 16232
count = 43
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 525.558960/16222, current 525.728149/16232: elapsed=169185.0us expected=173719.4us +- 868.6us, error 2.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 525.558960/16222, current 525.728149/16232: elapsed=169185.0us expected=173719.4us
(kms_flip:7718) INFO: Event vblank: expected 432, counted 440, passrate = 11.36%, encoder type 2
(kms_flip:7718) DEBUG: dropped frames, expected 432, counted 440, passrate = 11.36%, encoder type 2
(kms_flip:7718) DEBUG: Retrying without a hotplug event
(kms_flip:7718) DEBUG: No stale events found
(kms_flip:7718) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:7718) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:7718) INFO: Expected frametime: 16667us; measured 16839.0us +- 1.966us accuracy 0.04%
(kms_flip:7718) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16839us +- 1.966us, difference 172.3us (87.6 sigma)
(kms_flip:7718) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 529.334106
current_received_ts = 529.333618
current_seq = 16446
count = 0
seq_step = 10
(kms_flip:7718) DEBUG: name = vblank
last_ts = 529.334106
last_received_ts = 529.333618
last_seq = 16446
current_ts = 529.502258
current_received_ts = 529.501770
current_seq = 16456
count = 1
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 529.334106/16446, current 529.502258/16456: elapsed=168176.0us expected=168390.0us +- 842.0us, error 0.1%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 529.502258
last_received_ts = 529.501770
last_seq = 16456
current_ts = 529.670471
current_received_ts = 529.669922
current_seq = 16466
count = 2
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 529.502258/16456, current 529.670471/16466: elapsed=168178.0us expected=168390.0us +- 842.0us, error 0.1%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 529.670471
last_received_ts = 529.669922
last_seq = 16466
current_ts = 529.838623
current_received_ts = 529.838135
current_seq = 16476
count = 3
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 529.670471/16466, current 529.838623/16476: elapsed=168178.0us expected=168390.0us +- 842.0us, error 0.1%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 529.838623
last_received_ts = 529.838135
last_seq = 16476
current_ts = 530.006836
current_received_ts = 530.006287
current_seq = 16486
count = 4
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 529.838623/16476, current 530.006836/16486: elapsed=168179.0us expected=168390.0us +- 842.0us, error 0.1%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.006836
last_received_ts = 530.006287
last_seq = 16486
current_ts = 530.174988
current_received_ts = 530.174438
current_seq = 16496
count = 5
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.006836/16486, current 530.174988/16496: elapsed=168165.0us expected=168390.0us +- 842.0us, error 0.1%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.174988
last_received_ts = 530.174438
last_seq = 16496
current_ts = 530.342957
current_received_ts = 530.342468
current_seq = 16506
count = 6
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.174988/16496, current 530.342957/16506: elapsed=167990.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.342957
last_received_ts = 530.342468
last_seq = 16506
current_ts = 530.510986
current_received_ts = 530.510376
current_seq = 16516
count = 7
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.342957/16506, current 530.510986/16516: elapsed=167998.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.510986
last_received_ts = 530.510376
last_seq = 16516
current_ts = 530.679016
current_received_ts = 530.678467
current_seq = 16526
count = 8
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.510986/16516, current 530.679016/16526: elapsed=168011.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.679016
last_received_ts = 530.678467
last_seq = 16526
current_ts = 530.846985
current_received_ts = 530.846497
current_seq = 16536
count = 9
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.679016/16526, current 530.846985/16536: elapsed=168004.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 530.846985
last_received_ts = 530.846497
last_seq = 16536
current_ts = 531.014954
current_received_ts = 531.014465
current_seq = 16546
count = 10
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 530.846985/16536, current 531.014954/16546: elapsed=167988.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.014954
last_received_ts = 531.014465
last_seq = 16546
current_ts = 531.182983
current_received_ts = 531.182434
current_seq = 16556
count = 11
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.014954/16546, current 531.182983/16556: elapsed=167988.0us expected=168390.0us +- 842.0us, error 0.2%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.182983
last_received_ts = 531.182434
last_seq = 16556
current_ts = 531.350830
current_received_ts = 531.350281
current_seq = 16566
count = 12
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.182983/16556, current 531.350830/16566: elapsed=167846.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.350830
last_received_ts = 531.350281
last_seq = 16566
current_ts = 531.518677
current_received_ts = 531.518127
current_seq = 16576
count = 13
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.350830/16566, current 531.518677/16576: elapsed=167844.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.518677
last_received_ts = 531.518127
last_seq = 16576
current_ts = 531.686523
current_received_ts = 531.685974
current_seq = 16586
count = 14
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.518677/16576, current 531.686523/16586: elapsed=167845.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.686523
last_received_ts = 531.685974
last_seq = 16586
current_ts = 531.854370
current_received_ts = 531.853821
current_seq = 16596
count = 15
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.686523/16586, current 531.854370/16596: elapsed=167845.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 531.854370
last_received_ts = 531.853821
last_seq = 16596
current_ts = 532.022217
current_received_ts = 532.021667
current_seq = 16606
count = 16
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 531.854370/16596, current 532.022217/16606: elapsed=167845.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.022217
last_received_ts = 532.021667
last_seq = 16606
current_ts = 532.190002
current_received_ts = 532.189514
current_seq = 16616
count = 17
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.022217/16606, current 532.190002/16616: elapsed=167821.0us expected=168390.0us +- 842.0us, error 0.3%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.190002
last_received_ts = 532.189514
last_seq = 16616
current_ts = 532.357727
current_received_ts = 532.357239
current_seq = 16626
count = 18
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.190002/16616, current 532.357727/16626: elapsed=167710.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.357727
last_received_ts = 532.357239
last_seq = 16626
current_ts = 532.525452
current_received_ts = 532.524902
current_seq = 16636
count = 19
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.357727/16626, current 532.525452/16636: elapsed=167711.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.525452
last_received_ts = 532.524902
last_seq = 16636
current_ts = 532.693115
current_received_ts = 532.692627
current_seq = 16646
count = 20
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.525452/16636, current 532.693115/16646: elapsed=167706.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.693115
last_received_ts = 532.692627
last_seq = 16646
current_ts = 532.860840
current_received_ts = 532.860352
current_seq = 16656
count = 21
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.693115/16646, current 532.860840/16656: elapsed=167709.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 532.860840
last_received_ts = 532.860352
last_seq = 16656
current_ts = 533.028564
current_received_ts = 533.028076
current_seq = 16666
count = 22
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 532.860840/16656, current 533.028564/16666: elapsed=167700.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.028564
last_received_ts = 533.028076
last_seq = 16666
current_ts = 533.196228
current_received_ts = 533.195740
current_seq = 16676
count = 23
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.028564/16666, current 533.196228/16676: elapsed=167696.0us expected=168390.0us +- 842.0us, error 0.4%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.196228
last_received_ts = 533.195740
last_seq = 16676
current_ts = 533.363831
current_received_ts = 533.363342
current_seq = 16686
count = 24
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.196228/16676, current 533.363831/16686: elapsed=167590.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.363831
last_received_ts = 533.363342
last_seq = 16686
current_ts = 533.531433
current_received_ts = 533.530945
current_seq = 16696
count = 25
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.363831/16686, current 533.531433/16696: elapsed=167587.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.531433
last_received_ts = 533.530945
last_seq = 16696
current_ts = 533.699036
current_received_ts = 533.698486
current_seq = 16706
count = 26
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.531433/16696, current 533.699036/16706: elapsed=167589.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.699036
last_received_ts = 533.698486
last_seq = 16706
current_ts = 533.866577
current_received_ts = 533.866089
current_seq = 16716
count = 27
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.699036/16706, current 533.866577/16716: elapsed=167591.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 533.866577
last_received_ts = 533.866089
last_seq = 16716
current_ts = 534.034180
current_received_ts = 534.033691
current_seq = 16726
count = 28
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 533.866577/16716, current 534.034180/16726: elapsed=167589.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.034180
last_received_ts = 534.033691
last_seq = 16726
current_ts = 534.201782
current_received_ts = 534.201294
current_seq = 16736
count = 29
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.034180/16726, current 534.201782/16736: elapsed=167567.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.201782
last_received_ts = 534.201294
last_seq = 16736
current_ts = 534.369263
current_received_ts = 534.368774
current_seq = 16746
count = 30
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.201782/16736, current 534.369263/16746: elapsed=167486.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 534.201782/16736, current 534.369263/16746: elapsed=167486.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.369263
last_received_ts = 534.368774
last_seq = 16746
current_ts = 534.536743
current_received_ts = 534.536255
current_seq = 16756
count = 31
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.369263/16746, current 534.536743/16756: elapsed=167485.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 534.369263/16746, current 534.536743/16756: elapsed=167485.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.536743
last_received_ts = 534.536255
last_seq = 16756
current_ts = 534.704224
current_received_ts = 534.703735
current_seq = 16766
count = 32
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.536743/16756, current 534.704224/16766: elapsed=167485.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 534.536743/16756, current 534.704224/16766: elapsed=167485.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.704224
last_received_ts = 534.703735
last_seq = 16766
current_ts = 534.871704
current_received_ts = 534.871216
current_seq = 16776
count = 33
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.704224/16766, current 534.871704/16776: elapsed=167485.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 534.704224/16766, current 534.871704/16776: elapsed=167485.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 534.871704
last_received_ts = 534.871216
last_seq = 16776
current_ts = 535.039185
current_received_ts = 535.038696
current_seq = 16786
count = 34
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 534.871704/16776, current 535.039185/16786: elapsed=167486.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 534.871704/16776, current 535.039185/16786: elapsed=167486.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.039185
last_received_ts = 535.038696
last_seq = 16786
current_ts = 535.206665
current_received_ts = 535.206177
current_seq = 16796
count = 35
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.039185/16786, current 535.206665/16796: elapsed=167464.0us expected=168390.0us +- 842.0us, error 0.5%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.039185/16786, current 535.206665/16796: elapsed=167464.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.206665
last_received_ts = 535.206177
last_seq = 16796
current_ts = 535.374023
current_received_ts = 535.373535
current_seq = 16806
count = 36
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.206665/16796, current 535.374023/16806: elapsed=167394.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.206665/16796, current 535.374023/16806: elapsed=167394.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.374023
last_received_ts = 535.373535
last_seq = 16806
current_ts = 535.541443
current_received_ts = 535.540955
current_seq = 16816
count = 37
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.374023/16806, current 535.541443/16816: elapsed=167394.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.374023/16806, current 535.541443/16816: elapsed=167394.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.541443
last_received_ts = 535.540955
last_seq = 16816
current_ts = 535.708801
current_received_ts = 535.708313
current_seq = 16826
count = 38
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.541443/16816, current 535.708801/16826: elapsed=167395.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.541443/16816, current 535.708801/16826: elapsed=167395.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.708801
last_received_ts = 535.708313
last_seq = 16826
current_ts = 535.876221
current_received_ts = 535.875732
current_seq = 16836
count = 39
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.708801/16826, current 535.876221/16836: elapsed=167394.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.708801/16826, current 535.876221/16836: elapsed=167394.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 535.876221
last_received_ts = 535.875732
last_seq = 16836
current_ts = 536.043640
current_received_ts = 536.043152
current_seq = 16846
count = 40
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 535.876221/16836, current 536.043640/16846: elapsed=167394.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 535.876221/16836, current 536.043640/16846: elapsed=167394.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 536.043640
last_received_ts = 536.043152
last_seq = 16846
current_ts = 536.210999
current_received_ts = 536.210510
current_seq = 16856
count = 41
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 536.043640/16846, current 536.210999/16856: elapsed=167371.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 536.043640/16846, current 536.210999/16856: elapsed=167371.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 536.210999
last_received_ts = 536.210510
last_seq = 16856
current_ts = 536.378296
current_received_ts = 536.377808
current_seq = 16866
count = 42
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 536.210999/16856, current 536.378296/16866: elapsed=167314.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 536.210999/16856, current 536.378296/16866: elapsed=167314.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 536.378296
last_received_ts = 536.377808
last_seq = 16866
current_ts = 536.545593
current_received_ts = 536.545166
current_seq = 16876
count = 43
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 536.378296/16866, current 536.545593/16876: elapsed=167306.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 536.378296/16866, current 536.545593/16876: elapsed=167306.0us expected=168390.0us
(kms_flip:7718) DEBUG: name = vblank
last_ts = 536.545593
last_received_ts = 536.545166
last_seq = 16876
current_ts = 536.712952
current_received_ts = 536.712463
current_seq = 16886
count = 44
seq_step = 10
(kms_flip:7718) DEBUG: vblank ts/seq: last 536.545593/16876, current 536.712952/16886: elapsed=167324.0us expected=168390.0us +- 842.0us, error 0.6%
(kms_flip:7718) DEBUG: inconsistent vblank ts/seq: last 536.545593/16876, current 536.712952/16886: elapsed=167324.0us expected=168390.0us
(kms_flip:7718) INFO: Event vblank: expected 448, counted 450, passrate = 66.67%, encoder type 2
(kms_flip:7718) DEBUG: dropped frames, expected 448, counted 450, passrate = 66.67%, encoder type 2
(kms_flip:7718) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:7718) CRITICAL: Failed assertion: !retried
(kms_flip:7718) igt_core-INFO: Stack trace:
(kms_flip:7718) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:7718) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:7718) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:7718) igt_core-INFO:   #3 ../tests/kms_flip.c:2184 __igt_unique____real_main2105()
(kms_flip:7718) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:7718) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:7718) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:7718) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A3: FAIL (19.085s)
Dmesg
<6> [515.497807] Console: switching to colour dummy device 80x25
<6> [515.498481] [IGT] kms_flip: executing
<7> [515.504220] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [515.505474] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [515.505834] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [515.522721] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [515.522729] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [515.526765] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [515.527342] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1]
<7> [515.527362] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [515.531422] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1] disconnected
<7> [515.531974] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [515.531980] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [515.536016] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [515.536550] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [515.536555] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [515.536814] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_USBC1
<7> [515.557885] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.579071] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.600248] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.621418] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.642572] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.663707] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.684865] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.705995] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.727567] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.748731] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.769891] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.791055] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.812213] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.833370] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.854525] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.875692] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.896864] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.918015] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.939169] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.960324] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [515.981466] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.002684] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.023839] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.044991] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.066146] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.087296] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.108508] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.129643] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.150795] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.171944] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.193103] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.214288] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.214477] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC1/DDI TC1/PHY C: Too many retries, giving up. First error: -110
<7> [516.214525] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [516.215303] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [516.215309] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [516.219345] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [516.219819] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [516.219824] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [516.223889] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<7> [516.224453] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1]
<7> [516.224458] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:588:DP-1]
<7> [516.228469] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:588:DP-1] disconnected
<7> [516.228486] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1]
<7> [516.228490] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:600:HDMI-A-1]
<7> [516.232633] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:600:HDMI-A-1] disconnected
<7> [516.232654] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2]
<7> [516.232660] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:607:HDMI-A-2]
<7> [516.236692] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:607:HDMI-A-2] disconnected
<7> [516.236713] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2]
<7> [516.236718] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:611:DP-2]
<7> [516.257858] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.278979] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.300126] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.321268] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.342418] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.363559] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.384714] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.405901] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.427063] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.448216] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.469369] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.490524] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.511694] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.532908] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.554103] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.575259] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.596439] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.617600] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.638762] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.659923] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.681084] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.702245] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.723408] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.744568] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.765728] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.786891] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.808054] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.829265] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.850434] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.871916] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.893088] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.914249] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX USBC1/DDI TC1/PHY C: timeout (status 0x7d40023f)
<7> [516.914433] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX USBC1/DDI TC1/PHY C: Too many retries, giving up. First error: -110
<7> [516.914479] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:611:DP-2] disconnected
<7> [516.914504] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3]
<7> [516.914510] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [516.939514] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:619:HDMI-A-3] ELD monitor FH-DP4K
<7> [516.939521] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:619:HDMI-A-3] ELD size 36, SAD count 2
<7> [516.939723] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0040 w(1)
<7> [516.939922] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK on first message, retry
<7> [516.940233] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpc] NAK for addr: 0040 w(1)
<7> [516.940390] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [516.940407] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [516.940570] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] DP dual mode adaptor (type 1 DVI) detected (max TMDS clock: 165000 kHz)
<7> [516.941334] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1440": 60 241700 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9 (CLOCK_HIGH)
<7> [516.941344] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "2560x1600": 60 267820 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9 (CLOCK_HIGH)
<7> [516.941352] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 297000 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [516.941359] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "3840x2160": 30 266640 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9 (CLOCK_HIGH)
<7> [516.941366] i915 0000:03:00.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080": 120 296703 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5 (CLOCK_HIGH)
<7> [516.941374] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:619:HDMI-A-3] probed modes:
<7> [516.941379] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [516.941383] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "3840x2160": 17 151100 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0x9
<7> [516.941387] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1600": 30 133910 2560 2608 2640 2720 1600 1603 1608 1641 0x40 0x9
<7> [516.941391] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "2560x1440": 30 120850 2560 2608 2640 2720 1440 1443 1448 1481 0x40 0x9
<7> [516.941394] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [516.941398] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1920x1080": 60 148352 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [516.941402] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1680x1050": 60 119000 1680 1728 1760 1840 1050 1053 1059 1080 0x40 0x9
<7> [516.941406] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5
<7> [516.941410] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [516.941413] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x1024": 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x40 0x5
<7> [516.941417] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1440x900": 60 88750 1440 1488 1520 1600 900 903 909 926 0x40 0x9
<7> [516.941421] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x800": 60 71000 1280 1328 1360 1440 800 803 809 823 0x40 0x9
<7> [516.941425] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
<7> [516.941429] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [516.941433] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5
<7> [516.941436] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5
<7> [516.941440] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
<7> [516.941444] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [516.941448] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
<7> [516.941451] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
<7> [516.941455] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
<7> [516.941459] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "800x600": 56 36000 800 824 896 1024 600 601 603 625 0x40 0x5
<7> [516.941463] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
<7> [516.941467] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 73 31500 640 664 704 832 480 489 492 520 0x40 0xa
<7> [516.941470] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
<7> [516.941474] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa
<7> [516.941523] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3]
<7> [516.941528] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:623:DP-3]
<7> [516.945624] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:623:DP-3] disconnected
<7> [516.945643] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4]
<7> [516.945647] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:631:HDMI-A-4]
<7> [516.949686] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:631:HDMI-A-4] disconnected
<6> [516.950934] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [516.958393] [IGT] kms_flip: starting dynamic subtest A-HDMI-A3
<7> [516.958911] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:637]
<7> [516.959103] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:638]
<7> [516.959539] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [516.960469] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.005123] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.005486] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.015113] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.015514] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_USBC1
<7> [517.015534] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.034771] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.035117] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [517.060968] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [517.061092] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [517.061290] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [517.061487] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [517.061670] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [517.061854] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [517.062037] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [517.062219] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [517.062413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [517.062596] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [517.062778] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [517.062959] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [517.063141] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [517.063322] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [517.063514] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [517.063697] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [517.063880] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [517.064062] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [517.064245] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [517.064457] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [517.064641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [517.064825] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [517.065007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [517.065188] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [517.065378] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [517.065562] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [517.065744] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [517.065927] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [517.066108] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [517.066299] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [517.066481] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [517.066663] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [517.066844] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [517.067025] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [517.067214] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [517.067398] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [517.067580] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [517.067762] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [517.067945] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [517.068128] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 2
<7> [517.068348] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [517.068525] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [517.068749] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [517.068928] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [517.069106] i915 0000:03:00.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> [517.069289] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [517.069469] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [517.069648] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [517.069848] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [517.070030] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [517.070219] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [517.070421] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [517.070622] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [517.070805] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [517.070986] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [517.071176] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [517.071358] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [517.071538] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [517.071718] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [517.071898] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [517.072085] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [517.072265] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [517.083949] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [517.101872] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [517.102132] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [517.102389] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [517.102630] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [517.102867] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [517.103095] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [517.103328] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [517.103562] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [517.103806] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [517.104040] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [517.104272] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [517.104531] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [517.104775] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [517.105013] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [517.105248] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [517.105480] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [517.105725] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [517.105958] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [517.106193] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [517.106426] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [517.106673] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [517.107074] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [517.107384] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [517.107697] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [517.107982] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [517.108936] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [517.109166] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [517.109375] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [517.109605] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [517.109659] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:619:HDMI-A-3]
<7> [517.109782] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:619:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [517.110026] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [517.110244] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [517.110744] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:610:DDI TC1/PHY C] [CRTC:171:pipe A]
<7> [517.110973] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [517.111211] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [517.111462] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [517.111699] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [517.111934] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [517.112174] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [517.112419] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [517.112661] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [517.112895] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [517.113129] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [517.113398] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [517.113636] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [517.113870] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [517.114105] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [517.114349] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [517.114585] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [517.114822] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [517.115059] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [517.115303] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [517.115538] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [517.115772] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [517.116007] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [517.116324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [517.116559] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [517.116793] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [517.117027] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [517.117271] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [517.117506] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [517.117741] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [517.117979] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [517.118222] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [517.118459] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [517.118694] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [517.118931] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [517.119176] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [517.119413] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [517.119651] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [517.119889] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [517.120179] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [517.120412] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [517.120701] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [517.120940] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [517.121171] i915 0000:03:00.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> [517.121397] i915 0000:03:00.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, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [517.121629] i915 0000:03:00.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, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [517.121870] i915 0000:03:00.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, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [517.122130] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [517.122365] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [517.122601] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [517.122869] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [517.123130] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [517.123366] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 2
<7> [517.123605] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 2
<7> [517.123856] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [517.124093] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [517.124325] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [517.124559] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [517.124805] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [517.125042] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [517.125277] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [517.125512] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [517.125754] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [517.125987] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [517.126220] i915 0000:03:00.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> [517.126455] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [517.126699] i915 0000:03:00.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> [517.126935] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [517.127173] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [517.127410] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [517.127652] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [517.127890] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [517.128127] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [517.128365] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [517.128609] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [517.128844] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [517.129082] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [517.129316] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [517.129563] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [517.129797] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, 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> [517.130032] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x40000000 gamma_enable: 0 csc_enable: 0
<7> [517.130270] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries
<7> [517.130515] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [517.130748] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.130982] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.131214] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.131459] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [517.131694] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [517.131926] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.132159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.132391] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [517.132638] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [517.132873] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [517.133109] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [517.133346] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [517.133590] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [517.133823] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [517.134055] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [517.134291] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [517.134534] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [517.134766] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [517.135001] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [517.135613] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [517.135864] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [517.136132] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [517.136381] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [517.136615] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [517.136851] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [517.137081] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [517.137326] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [517.137557] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [517.137789] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [517.138023] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [517.138267] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [517.138498] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [517.138729] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [517.138960] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [517.139202] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [517.139435] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [517.139669] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [517.140011] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [517.140443] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [517.140683] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [517.140978] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [517.141400] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [517.158851] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [517.159045] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [517.175891] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [517.176187] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [527.795685] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [527.796236] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [527.797321] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [527.798400] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [527.799364] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [527.800317] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [527.801282] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [527.801573] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [527.801735] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [527.801898] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [527.802062] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [527.802226] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [527.802389] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [527.802592] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [527.802823] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [527.802985] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [527.803146] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [527.803309] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [527.803497] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [527.803701] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [527.803862] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [527.804024] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [527.804186] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [527.804350] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [527.804551] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [527.804741] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [527.804903] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [527.805064] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [527.805226] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [527.805386] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [527.805599] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [527.805775] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [527.805936] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [527.806099] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [527.806277] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [527.806450] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [527.806630] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [527.806793] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [527.806957] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [527.807119] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:171:pipe A] releasing DPLL 2
<7> [527.807312] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [527.807559] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [527.807834] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [527.807990] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [527.808149] i915 0000:03:00.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> [527.808307] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 1, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [527.808538] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 16, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [527.808711] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [527.808894] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 0 num active planes 0
<7> [527.809056] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [527.809216] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [527.809424] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [527.809623] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [527.809820] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: no [modeset]
<7> [527.809998] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [NOFB], visible: no
<7> [527.810175] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [527.810341] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [527.810604] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [527.810781] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [527.810943] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [527.811105] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [527.811265] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [527.826028] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [527.843214] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [527.843424] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 2 (active 0x1, on? 1) for [CRTC:171:pipe A]
<7> [527.843627] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 2
<7> [527.843821] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:35:plane 1A]
<7> [527.843997] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [527.844178] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [527.844346] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [527.844513] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [527.844681] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [527.844849] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [527.845015] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [527.845212] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [527.845382] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [527.845549] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [527.845717] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [527.845885] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [527.846061] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [527.846230] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [527.846399] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [527.846566] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [527.846733] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [527.847031] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [527.847264] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [527.847475] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [527.847681] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<7> [527.848343] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:309:pipe C]
<7> [527.848551] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:447:pipe B]
<7> [527.848743] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:585:pipe D]
<7> [527.848936] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:171:pipe A]
<7> [527.848974] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:619:HDMI-A-3]
<7> [527.849069] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:619:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [527.849246] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [527.849406] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [527.849578] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:610:DDI TC1/PHY C] [CRTC:171:pipe A]
<7> [527.849739] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [527.849909] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [527.850090] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [527.850260] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [527.850429] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [527.850599] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [527.850769] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [527.850940] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [527.851122] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [527.851293] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [527.851463] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [527.851633] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [527.851802] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [527.851978] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [527.852147] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [527.852318] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [527.852488] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [527.852658] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [527.852827] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [527.853077] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [527.853247] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [527.853417] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [527.853585] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [527.853754] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [527.853923] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [527.854099] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [527.854270] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [527.854439] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [527.854607] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [527.854776] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [527.854952] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [527.855123] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [527.855293] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [527.855463] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [527.855633] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [527.855802] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [527.855980] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:171:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [527.856150] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:171:pipe A] fastset requirement not met, forcing full modeset
<7> [527.856350] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [527.856514] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:171:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [527.856722] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [527.856890] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:165:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [527.857062] i915 0000:03:00.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> [527.857230] i915 0000:03:00.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, 5, 5, 5, 8, 8, 9, 10, 0, 0, 0
<7> [527.857395] i915 0000:03:00.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, 81, 81, 81, 129, 129, 145, 161, 30, 0, 0
<7> [527.857563] i915 0000:03:00.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, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [527.857750] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:171:pipe A] data rate 594000 num active planes 1
<7> [527.857925] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [527.858094] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [527.858281] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:171:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [527.858467] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [527.858638] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:171:pipe A] allocated DPLL 2
<7> [527.858809] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:171:pipe A] reserving DPLL 2
<7> [527.858995] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:171:pipe A] enable: yes [modeset]
<7> [527.859164] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [527.859332] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [527.859499] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [527.859670] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [527.859839] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [527.860018] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [527.860187] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [527.860356] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [527.860525] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [527.860693] i915 0000:03:00.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> [527.860869] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1081, vmax vblank: 1081, vmin vtotal: 1125, vmax vtotal: 1125
<7> [527.861037] i915 0000:03:00.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> [527.861206] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [527.861377] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [527.861546] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [527.861715] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [527.861892] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=148500, hd=1920 hb=1920-2200 hs=2008-2052 ht=2200, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5
<7> [527.862063] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [527.862232] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [527.862403] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [527.862572] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [527.862742] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [527.862921] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [527.863091] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [527.863258] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1001d0, 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> [527.863427] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x40000000 gamma_enable: 0 csc_enable: 0
<7> [527.863595] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 256 entries
<7> [527.863762] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [527.863937] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.864106] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.864274] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.864452] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [527.864617] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [527.864785] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.864992] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.865158] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [527.865322] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [527.865486] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:637] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [527.865653] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [527.865845] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [527.866026] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [527.866191] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [527.866358] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [527.866523] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [527.866688] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:plane 6A] fb: [NOFB], visible: no
<7> [527.866894] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:155:plane 7A] fb: [NOFB], visible: no
<7> [527.867060] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:165:cursor A] fb: [NOFB], visible: no
<7> [527.867340] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [527.867526] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [527.867721] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:587:DDI A/PHY A]
<7> [527.867931] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST A]
<7> [527.868095] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST B]
<7> [527.868258] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST C]
<7> [527.868423] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:592:DP-MST D]
<7> [527.868588] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:606:DDI B/PHY B]
<7> [527.868753] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:610:DDI TC1/PHY C]
<7> [527.868954] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST A]
<7> [527.869118] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST B]
<7> [527.869281] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST C]
<7> [527.869443] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:615:DP-MST D]
<7> [527.869608] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:622:DDI TC2/PHY D]
<7> [527.869795] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST A]
<7> [527.869973] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST B]
<7> [527.870136] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST C]
<7> [527.870299] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:627:DP-MST D]
<7> [527.870543] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [527.870928] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 2 (active 0x1, on? 0) for [CRTC:171:pipe A]
<7> [527.871095] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 2
<7> [527.871312] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [527.871646] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [527.888964] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [527.889138] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [527.905897] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:619:HDMI-A-3]
<7> [527.906186] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:171:pipe A]
<6> [536.046359] [IGT] kms_flip: finished subtest A-HDMI-A3, FAIL
Created at 2026-05-08 19:07:16