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

Result: Fail

git-log-oneline i915_display_info20 igt_runner20 runtimes20 results20.json results20-i915-load.json guc_logs20.tar i915_display_info_post_exec20 boot20 dmesg20

DetailValue
Duration 19.01 seconds
Hostname 
shard-dg1-14
Igt-Version 
IGT-Version: 2.3-gb7cc5ebfe (x86_64) (Linux: 6.19.0-rc8-CI_DRM_17924-g1c0387941402+ x86_64)
Out 
Using IGT_SRANDOM=1770156336 for randomisation
Opened device: /dev/dri/card0
Using monotonic timestamps
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 17298.7us +- 2.089us accuracy 0.04%
vblank interval differs from modeline! expected 16666.7us, measured 17299us +- 2.089us, difference 632.0us (302.6 sigma)
Event vblank: expected 433, counted 440, passrate = 15.91%, encoder type 2
Expected frametime: 16667us; measured 16818.4us +- 2.778us accuracy 0.05%
vblank interval differs from modeline! expected 16666.7us, measured 16818us +- 2.778us, difference 151.7us (54.6 sigma)
Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1889 run_test()
  #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
  #4 ../tests/kms_flip.c:2045 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest A-HDMI-A1: FAIL (19.014s)
Err 
Starting subtest: blocking-wf_vblank
Starting dynamic subtest: A-HDMI-A1
(kms_flip:2498) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2498) CRITICAL: Failed assertion: !retried
Dynamic subtest A-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) igt_fb-DEBUG: Test requirement passed: gem_has_contexts(fb->fd)
(kms_flip:2498) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2498) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2498) DEBUG: No stale events found
(kms_flip:2498) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2498) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2498) INFO: Expected frametime: 16667us; measured 17298.7us +- 2.089us accuracy 0.04%
(kms_flip:2498) INFO: vblank interval differs from modeline! expected 16666.7us, measured 17299us +- 2.089us, difference 632.0us (302.6 sigma)
(kms_flip:2498) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 473.138763
current_received_ts = 473.138245
current_seq = 6903
count = 0
seq_step = 10
(kms_flip:2498) DEBUG: name = vblank
last_ts = 473.138763
last_received_ts = 473.138245
last_seq = 6903
current_ts = 473.311432
current_received_ts = 473.310944
current_seq = 6913
count = 1
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 473.138763/6903, current 473.311432/6913: elapsed=172685.0us expected=172986.9us +- 864.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 473.311432
last_received_ts = 473.310944
last_seq = 6913
current_ts = 473.483643
current_received_ts = 473.483154
current_seq = 6923
count = 2
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 473.311432/6913, current 473.483643/6923: elapsed=172206.0us expected=172986.9us +- 864.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 473.483643
last_received_ts = 473.483154
last_seq = 6923
current_ts = 473.655853
current_received_ts = 473.655365
current_seq = 6933
count = 3
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 473.483643/6923, current 473.655853/6933: elapsed=172215.0us expected=172986.9us +- 864.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 473.655853
last_received_ts = 473.655365
last_seq = 6933
current_ts = 473.828064
current_received_ts = 473.827576
current_seq = 6943
count = 4
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 473.655853/6933, current 473.828064/6943: elapsed=172209.0us expected=172986.9us +- 864.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 473.828064
last_received_ts = 473.827576
last_seq = 6943
current_ts = 474.000275
current_received_ts = 473.999756
current_seq = 6953
count = 5
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 473.828064/6943, current 474.000275/6953: elapsed=172217.0us expected=172986.9us +- 864.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.000275
last_received_ts = 473.999756
last_seq = 6953
current_ts = 474.172485
current_received_ts = 474.171997
current_seq = 6963
count = 6
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.000275/6953, current 474.172485/6963: elapsed=172206.0us expected=172986.9us +- 864.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.172485
last_received_ts = 474.171997
last_seq = 6963
current_ts = 474.344299
current_received_ts = 474.343811
current_seq = 6973
count = 7
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.172485/6963, current 474.344299/6973: elapsed=171803.0us expected=172986.9us +- 864.9us, error 0.7%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 474.172485/6963, current 474.344299/6973: elapsed=171803.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.344299
last_received_ts = 474.343811
last_seq = 6973
current_ts = 474.515808
current_received_ts = 474.515320
current_seq = 6983
count = 8
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.344299/6973, current 474.515808/6983: elapsed=171529.0us expected=172986.9us +- 864.9us, error 0.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 474.344299/6973, current 474.515808/6983: elapsed=171529.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.515808
last_received_ts = 474.515320
last_seq = 6983
current_ts = 474.687347
current_received_ts = 474.686829
current_seq = 6993
count = 9
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.515808/6983, current 474.687347/6993: elapsed=171527.0us expected=172986.9us +- 864.9us, error 0.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 474.515808/6983, current 474.687347/6993: elapsed=171527.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.687347
last_received_ts = 474.686829
last_seq = 6993
current_ts = 474.858887
current_received_ts = 474.858368
current_seq = 7003
count = 10
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.687347/6993, current 474.858887/7003: elapsed=171531.0us expected=172986.9us +- 864.9us, error 0.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 474.687347/6993, current 474.858887/7003: elapsed=171531.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 474.858887
last_received_ts = 474.858368
last_seq = 7003
current_ts = 475.030396
current_received_ts = 475.029877
current_seq = 7013
count = 11
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 474.858887/7003, current 475.030396/7013: elapsed=171530.0us expected=172986.9us +- 864.9us, error 0.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 474.858887/7003, current 475.030396/7013: elapsed=171530.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.030396
last_received_ts = 475.029877
last_seq = 7013
current_ts = 475.201935
current_received_ts = 475.201416
current_seq = 7023
count = 12
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.030396/7013, current 475.201935/7023: elapsed=171529.0us expected=172986.9us +- 864.9us, error 0.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.030396/7013, current 475.201935/7023: elapsed=171529.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.201935
last_received_ts = 475.201416
last_seq = 7023
current_ts = 475.373047
current_received_ts = 475.372528
current_seq = 7033
count = 13
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.201935/7023, current 475.373047/7033: elapsed=171111.0us expected=172986.9us +- 864.9us, error 1.1%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.201935/7023, current 475.373047/7033: elapsed=171111.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.373047
last_received_ts = 475.372528
last_seq = 7033
current_ts = 475.543976
current_received_ts = 475.543457
current_seq = 7043
count = 14
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.373047/7033, current 475.543976/7043: elapsed=170932.0us expected=172986.9us +- 864.9us, error 1.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.373047/7033, current 475.543976/7043: elapsed=170932.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.543976
last_received_ts = 475.543457
last_seq = 7043
current_ts = 475.714905
current_received_ts = 475.714386
current_seq = 7053
count = 15
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.543976/7043, current 475.714905/7053: elapsed=170935.0us expected=172986.9us +- 864.9us, error 1.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.543976/7043, current 475.714905/7053: elapsed=170935.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.714905
last_received_ts = 475.714386
last_seq = 7053
current_ts = 475.885834
current_received_ts = 475.885315
current_seq = 7063
count = 16
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.714905/7053, current 475.885834/7063: elapsed=170928.0us expected=172986.9us +- 864.9us, error 1.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.714905/7053, current 475.885834/7063: elapsed=170928.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 475.885834
last_received_ts = 475.885315
last_seq = 7063
current_ts = 476.056763
current_received_ts = 476.056274
current_seq = 7073
count = 17
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 475.885834/7063, current 476.056763/7073: elapsed=170932.0us expected=172986.9us +- 864.9us, error 1.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 475.885834/7063, current 476.056763/7073: elapsed=170932.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.056763
last_received_ts = 476.056274
last_seq = 7073
current_ts = 476.227722
current_received_ts = 476.227173
current_seq = 7083
count = 18
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.056763/7073, current 476.227722/7083: elapsed=170938.0us expected=172986.9us +- 864.9us, error 1.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.056763/7073, current 476.227722/7083: elapsed=170938.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.227722
last_received_ts = 476.227173
last_seq = 7083
current_ts = 476.398163
current_received_ts = 476.397644
current_seq = 7093
count = 19
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.227722/7083, current 476.398163/7093: elapsed=170446.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.227722/7083, current 476.398163/7093: elapsed=170446.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.398163
last_received_ts = 476.397644
last_seq = 7093
current_ts = 476.568573
current_received_ts = 476.568054
current_seq = 7103
count = 20
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.398163/7093, current 476.568573/7103: elapsed=170409.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.398163/7093, current 476.568573/7103: elapsed=170409.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.568573
last_received_ts = 476.568054
last_seq = 7103
current_ts = 476.738983
current_received_ts = 476.738464
current_seq = 7113
count = 21
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.568573/7103, current 476.738983/7113: elapsed=170409.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.568573/7103, current 476.738983/7113: elapsed=170409.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.738983
last_received_ts = 476.738464
last_seq = 7113
current_ts = 476.909393
current_received_ts = 476.908875
current_seq = 7123
count = 22
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.738983/7113, current 476.909393/7123: elapsed=170409.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.738983/7113, current 476.909393/7123: elapsed=170409.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 476.909393
last_received_ts = 476.908875
last_seq = 7123
current_ts = 477.079803
current_received_ts = 477.079224
current_seq = 7133
count = 23
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 476.909393/7123, current 477.079803/7133: elapsed=170414.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 476.909393/7123, current 477.079803/7133: elapsed=170414.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.079803
last_received_ts = 477.079224
last_seq = 7133
current_ts = 477.250214
current_received_ts = 477.249695
current_seq = 7143
count = 24
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.079803/7133, current 477.250214/7143: elapsed=170405.0us expected=172986.9us +- 864.9us, error 1.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.079803/7133, current 477.250214/7143: elapsed=170405.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.250214
last_received_ts = 477.249695
last_seq = 7143
current_ts = 477.420166
current_received_ts = 477.419647
current_seq = 7153
count = 25
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.250214/7143, current 477.420166/7153: elapsed=169952.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.250214/7143, current 477.420166/7153: elapsed=169952.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.420166
last_received_ts = 477.419647
last_seq = 7153
current_ts = 477.590118
current_received_ts = 477.589600
current_seq = 7163
count = 26
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.420166/7153, current 477.590118/7163: elapsed=169952.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.420166/7153, current 477.590118/7163: elapsed=169952.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.590118
last_received_ts = 477.589600
last_seq = 7163
current_ts = 477.760071
current_received_ts = 477.759552
current_seq = 7173
count = 27
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.590118/7163, current 477.760071/7173: elapsed=169952.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.590118/7163, current 477.760071/7173: elapsed=169952.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.760071
last_received_ts = 477.759552
last_seq = 7173
current_ts = 477.930023
current_received_ts = 477.929504
current_seq = 7183
count = 28
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.760071/7173, current 477.930023/7183: elapsed=169951.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.760071/7173, current 477.930023/7183: elapsed=169951.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 477.930023
last_received_ts = 477.929504
last_seq = 7183
current_ts = 478.099976
current_received_ts = 478.099457
current_seq = 7193
count = 29
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 477.930023/7183, current 478.099976/7193: elapsed=169952.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 477.930023/7183, current 478.099976/7193: elapsed=169952.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.099976
last_received_ts = 478.099457
last_seq = 7193
current_ts = 478.269867
current_received_ts = 478.269348
current_seq = 7203
count = 30
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.099976/7193, current 478.269867/7203: elapsed=169894.0us expected=172986.9us +- 864.9us, error 1.8%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.099976/7193, current 478.269867/7203: elapsed=169894.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.269867
last_received_ts = 478.269348
last_seq = 7203
current_ts = 478.439423
current_received_ts = 478.438904
current_seq = 7213
count = 31
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.269867/7203, current 478.439423/7213: elapsed=169552.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.269867/7203, current 478.439423/7213: elapsed=169552.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.439423
last_received_ts = 478.438904
last_seq = 7213
current_ts = 478.608948
current_received_ts = 478.608459
current_seq = 7223
count = 32
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.439423/7213, current 478.608948/7223: elapsed=169551.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.439423/7213, current 478.608948/7223: elapsed=169551.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.608948
last_received_ts = 478.608459
last_seq = 7223
current_ts = 478.778503
current_received_ts = 478.777985
current_seq = 7233
count = 33
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.608948/7223, current 478.778503/7233: elapsed=169552.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.608948/7223, current 478.778503/7233: elapsed=169552.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.778503
last_received_ts = 478.777985
last_seq = 7233
current_ts = 478.948059
current_received_ts = 478.947540
current_seq = 7243
count = 34
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.778503/7233, current 478.948059/7243: elapsed=169552.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.778503/7233, current 478.948059/7243: elapsed=169552.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 478.948059
last_received_ts = 478.947540
last_seq = 7243
current_ts = 479.117615
current_received_ts = 479.117096
current_seq = 7253
count = 35
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 478.948059/7243, current 479.117615/7253: elapsed=169551.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 478.948059/7243, current 479.117615/7253: elapsed=169551.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.117615
last_received_ts = 479.117096
last_seq = 7253
current_ts = 479.287109
current_received_ts = 479.286591
current_seq = 7263
count = 36
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.117615/7253, current 479.287109/7263: elapsed=169483.0us expected=172986.9us +- 864.9us, error 2.0%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.117615/7253, current 479.287109/7263: elapsed=169483.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.287109
last_received_ts = 479.286591
last_seq = 7263
current_ts = 479.456299
current_received_ts = 479.455780
current_seq = 7273
count = 37
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.287109/7263, current 479.456299/7273: elapsed=169201.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.287109/7263, current 479.456299/7273: elapsed=169201.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.456299
last_received_ts = 479.455780
last_seq = 7273
current_ts = 479.625488
current_received_ts = 479.624969
current_seq = 7283
count = 38
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.456299/7273, current 479.625488/7283: elapsed=169201.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.456299/7273, current 479.625488/7283: elapsed=169201.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.625488
last_received_ts = 479.624969
last_seq = 7283
current_ts = 479.794708
current_received_ts = 479.794189
current_seq = 7293
count = 39
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.625488/7283, current 479.794708/7293: elapsed=169202.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.625488/7283, current 479.794708/7293: elapsed=169202.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.794708
last_received_ts = 479.794189
last_seq = 7293
current_ts = 479.963898
current_received_ts = 479.963379
current_seq = 7303
count = 40
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.794708/7293, current 479.963898/7303: elapsed=169203.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.794708/7293, current 479.963898/7303: elapsed=169203.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 479.963898
last_received_ts = 479.963379
last_seq = 7303
current_ts = 480.133118
current_received_ts = 480.132599
current_seq = 7313
count = 41
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 479.963898/7303, current 480.133118/7313: elapsed=169200.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 479.963898/7303, current 480.133118/7313: elapsed=169200.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 480.133118
last_received_ts = 480.132599
last_seq = 7313
current_ts = 480.302216
current_received_ts = 480.301697
current_seq = 7323
count = 42
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 480.133118/7313, current 480.302216/7323: elapsed=169110.0us expected=172986.9us +- 864.9us, error 2.2%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 480.133118/7313, current 480.302216/7323: elapsed=169110.0us expected=172986.9us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 480.302216
last_received_ts = 480.301697
last_seq = 7323
current_ts = 480.471100
current_received_ts = 480.470581
current_seq = 7333
count = 43
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 480.302216/7323, current 480.471100/7333: elapsed=168896.0us expected=172986.9us +- 864.9us, error 2.4%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 480.302216/7323, current 480.471100/7333: elapsed=168896.0us expected=172986.9us
(kms_flip:2498) INFO: Event vblank: expected 433, counted 440, passrate = 15.91%, encoder type 2
(kms_flip:2498) DEBUG: dropped frames, expected 433, counted 440, passrate = 15.91%, encoder type 2
(kms_flip:2498) DEBUG: Retrying without a hotplug event
(kms_flip:2498) DEBUG: No stale events found
(kms_flip:2498) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2498) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2498) INFO: Expected frametime: 16667us; measured 16818.4us +- 2.778us accuracy 0.05%
(kms_flip:2498) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16818us +- 2.778us, difference 151.7us (54.6 sigma)
(kms_flip:2498) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 484.056702
current_received_ts = 484.056183
current_seq = 7546
count = 0
seq_step = 10
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.056702
last_received_ts = 484.056183
last_seq = 7546
current_ts = 484.224884
current_received_ts = 484.224365
current_seq = 7556
count = 1
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.056702/7546, current 484.224884/7556: elapsed=168186.0us expected=168183.8us +- 840.9us, error 0.0%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.224884
last_received_ts = 484.224365
last_seq = 7556
current_ts = 484.392914
current_received_ts = 484.392395
current_seq = 7566
count = 2
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.224884/7556, current 484.392914/7566: elapsed=168027.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.392914
last_received_ts = 484.392395
last_seq = 7566
current_ts = 484.560913
current_received_ts = 484.560394
current_seq = 7576
count = 3
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.392914/7566, current 484.560913/7576: elapsed=168007.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.560913
last_received_ts = 484.560394
last_seq = 7576
current_ts = 484.728912
current_received_ts = 484.728394
current_seq = 7586
count = 4
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.560913/7576, current 484.728912/7586: elapsed=168007.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.728912
last_received_ts = 484.728394
last_seq = 7586
current_ts = 484.896942
current_received_ts = 484.896423
current_seq = 7596
count = 5
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.728912/7586, current 484.896942/7596: elapsed=168008.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 484.896942
last_received_ts = 484.896423
last_seq = 7596
current_ts = 485.064941
current_received_ts = 485.064423
current_seq = 7606
count = 6
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 484.896942/7596, current 485.064941/7606: elapsed=168008.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.064941
last_received_ts = 485.064423
last_seq = 7606
current_ts = 485.232941
current_received_ts = 485.232422
current_seq = 7616
count = 7
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.064941/7606, current 485.232941/7616: elapsed=168008.0us expected=168183.8us +- 840.9us, error 0.1%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.232941
last_received_ts = 485.232422
last_seq = 7616
current_ts = 485.400818
current_received_ts = 485.400299
current_seq = 7626
count = 8
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.232941/7616, current 485.400818/7626: elapsed=167866.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.400818
last_received_ts = 485.400299
last_seq = 7626
current_ts = 485.568665
current_received_ts = 485.568146
current_seq = 7636
count = 9
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.400818/7626, current 485.568665/7636: elapsed=167851.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.568665
last_received_ts = 485.568146
last_seq = 7636
current_ts = 485.736511
current_received_ts = 485.735992
current_seq = 7646
count = 10
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.568665/7636, current 485.736511/7646: elapsed=167850.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.736511
last_received_ts = 485.735992
last_seq = 7646
current_ts = 485.904358
current_received_ts = 485.903839
current_seq = 7656
count = 11
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.736511/7646, current 485.904358/7656: elapsed=167852.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 485.904358
last_received_ts = 485.903839
last_seq = 7656
current_ts = 486.072205
current_received_ts = 486.071686
current_seq = 7666
count = 12
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 485.904358/7656, current 486.072205/7666: elapsed=167851.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.072205
last_received_ts = 486.071686
last_seq = 7666
current_ts = 486.240051
current_received_ts = 486.239532
current_seq = 7676
count = 13
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.072205/7666, current 486.240051/7676: elapsed=167850.0us expected=168183.8us +- 840.9us, error 0.2%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.240051
last_received_ts = 486.239532
last_seq = 7676
current_ts = 486.407776
current_received_ts = 486.407257
current_seq = 7686
count = 14
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.240051/7676, current 486.407776/7686: elapsed=167716.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.407776
last_received_ts = 486.407257
last_seq = 7686
current_ts = 486.575500
current_received_ts = 486.574982
current_seq = 7696
count = 15
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.407776/7686, current 486.575500/7696: elapsed=167713.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.575500
last_received_ts = 486.574982
last_seq = 7696
current_ts = 486.743195
current_received_ts = 486.742676
current_seq = 7706
count = 16
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.575500/7696, current 486.743195/7706: elapsed=167714.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.743195
last_received_ts = 486.742676
last_seq = 7706
current_ts = 486.910919
current_received_ts = 486.910400
current_seq = 7716
count = 17
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.743195/7706, current 486.910919/7716: elapsed=167713.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 486.910919
last_received_ts = 486.910400
last_seq = 7716
current_ts = 487.078644
current_received_ts = 487.078125
current_seq = 7726
count = 18
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 486.910919/7716, current 487.078644/7726: elapsed=167713.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.078644
last_received_ts = 487.078125
last_seq = 7726
current_ts = 487.246338
current_received_ts = 487.245819
current_seq = 7736
count = 19
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.078644/7726, current 487.246338/7736: elapsed=167716.0us expected=168183.8us +- 840.9us, error 0.3%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.246338
last_received_ts = 487.245819
last_seq = 7736
current_ts = 487.413940
current_received_ts = 487.413422
current_seq = 7746
count = 20
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.246338/7736, current 487.413940/7746: elapsed=167590.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.413940
last_received_ts = 487.413422
last_seq = 7746
current_ts = 487.581543
current_received_ts = 487.581055
current_seq = 7756
count = 21
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.413940/7746, current 487.581543/7756: elapsed=167593.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.581543
last_received_ts = 487.581055
last_seq = 7756
current_ts = 487.749115
current_received_ts = 487.748596
current_seq = 7766
count = 22
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.581543/7756, current 487.749115/7766: elapsed=167593.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.749115
last_received_ts = 487.748596
last_seq = 7766
current_ts = 487.916718
current_received_ts = 487.916199
current_seq = 7776
count = 23
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.749115/7766, current 487.916718/7776: elapsed=167593.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 487.916718
last_received_ts = 487.916199
last_seq = 7776
current_ts = 488.084320
current_received_ts = 488.083801
current_seq = 7786
count = 24
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 487.916718/7776, current 488.084320/7786: elapsed=167593.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.084320
last_received_ts = 488.083801
last_seq = 7786
current_ts = 488.251892
current_received_ts = 488.251404
current_seq = 7796
count = 25
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.084320/7786, current 488.251892/7796: elapsed=167593.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.251892
last_received_ts = 488.251404
last_seq = 7796
current_ts = 488.419403
current_received_ts = 488.418884
current_seq = 7806
count = 26
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.251892/7796, current 488.419403/7806: elapsed=167488.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.419403
last_received_ts = 488.418884
last_seq = 7806
current_ts = 488.586884
current_received_ts = 488.586365
current_seq = 7816
count = 27
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.419403/7806, current 488.586884/7816: elapsed=167487.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.586884
last_received_ts = 488.586365
last_seq = 7816
current_ts = 488.754364
current_received_ts = 488.753845
current_seq = 7826
count = 28
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.586884/7816, current 488.754364/7826: elapsed=167487.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.754364
last_received_ts = 488.753845
last_seq = 7826
current_ts = 488.921844
current_received_ts = 488.921326
current_seq = 7836
count = 29
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.754364/7826, current 488.921844/7836: elapsed=167488.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 488.921844
last_received_ts = 488.921326
last_seq = 7836
current_ts = 489.089355
current_received_ts = 489.088837
current_seq = 7846
count = 30
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 488.921844/7836, current 489.089355/7846: elapsed=167488.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.089355
last_received_ts = 489.088837
last_seq = 7846
current_ts = 489.256836
current_received_ts = 489.256317
current_seq = 7856
count = 31
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.089355/7846, current 489.256836/7856: elapsed=167479.0us expected=168183.8us +- 840.9us, error 0.4%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.256836
last_received_ts = 489.256317
last_seq = 7856
current_ts = 489.424225
current_received_ts = 489.423706
current_seq = 7866
count = 32
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.256836/7856, current 489.424225/7866: elapsed=167396.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.424225
last_received_ts = 489.423706
last_seq = 7866
current_ts = 489.591614
current_received_ts = 489.591095
current_seq = 7876
count = 33
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.424225/7866, current 489.591614/7876: elapsed=167395.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.591614
last_received_ts = 489.591095
last_seq = 7876
current_ts = 489.759003
current_received_ts = 489.758484
current_seq = 7886
count = 34
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.591614/7876, current 489.759003/7886: elapsed=167395.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.759003
last_received_ts = 489.758484
last_seq = 7886
current_ts = 489.926392
current_received_ts = 489.925842
current_seq = 7896
count = 35
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.759003/7886, current 489.926392/7896: elapsed=167394.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 489.926392
last_received_ts = 489.925842
last_seq = 7896
current_ts = 490.093811
current_received_ts = 490.093292
current_seq = 7906
count = 36
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 489.926392/7896, current 490.093811/7906: elapsed=167397.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.093811
last_received_ts = 490.093292
last_seq = 7906
current_ts = 490.261200
current_received_ts = 490.260681
current_seq = 7916
count = 37
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.093811/7906, current 490.261200/7916: elapsed=167390.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.261200
last_received_ts = 490.260681
last_seq = 7916
current_ts = 490.428497
current_received_ts = 490.428040
current_seq = 7926
count = 38
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.261200/7916, current 490.428497/7926: elapsed=167312.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 490.261200/7916, current 490.428497/7926: elapsed=167312.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.428497
last_received_ts = 490.428040
last_seq = 7926
current_ts = 490.595825
current_received_ts = 490.595306
current_seq = 7936
count = 39
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.428497/7926, current 490.595825/7936: elapsed=167315.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 490.428497/7926, current 490.595825/7936: elapsed=167315.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.595825
last_received_ts = 490.595306
last_seq = 7936
current_ts = 490.763123
current_received_ts = 490.762634
current_seq = 7946
count = 40
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.595825/7936, current 490.763123/7946: elapsed=167316.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 490.595825/7936, current 490.763123/7946: elapsed=167316.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.763123
last_received_ts = 490.762634
last_seq = 7946
current_ts = 490.930450
current_received_ts = 490.929962
current_seq = 7956
count = 41
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.763123/7946, current 490.930450/7956: elapsed=167322.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 490.763123/7946, current 490.930450/7956: elapsed=167322.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 490.930450
last_received_ts = 490.929962
last_seq = 7956
current_ts = 491.097748
current_received_ts = 491.097260
current_seq = 7966
count = 42
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 490.930450/7956, current 491.097748/7966: elapsed=167307.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 490.930450/7956, current 491.097748/7966: elapsed=167307.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 491.097748
last_received_ts = 491.097260
last_seq = 7966
current_ts = 491.265076
current_received_ts = 491.264557
current_seq = 7976
count = 43
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 491.097748/7966, current 491.265076/7976: elapsed=167305.0us expected=168183.8us +- 840.9us, error 0.5%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 491.097748/7966, current 491.265076/7976: elapsed=167305.0us expected=168183.8us
(kms_flip:2498) DEBUG: name = vblank
last_ts = 491.265076
last_received_ts = 491.264557
last_seq = 7976
current_ts = 491.432312
current_received_ts = 491.431824
current_seq = 7986
count = 44
seq_step = 10
(kms_flip:2498) DEBUG: vblank ts/seq: last 491.265076/7976, current 491.432312/7986: elapsed=167245.0us expected=168183.8us +- 840.9us, error 0.6%
(kms_flip:2498) DEBUG: inconsistent vblank ts/seq: last 491.265076/7976, current 491.432312/7986: elapsed=167245.0us expected=168183.8us
(kms_flip:2498) INFO: Event vblank: expected 448, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2498) DEBUG: dropped frames, expected 448, counted 450, passrate = 84.44%, encoder type 2
(kms_flip:2498) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1709:
(kms_flip:2498) CRITICAL: Failed assertion: !retried
(kms_flip:2498) igt_core-INFO: Stack trace:
(kms_flip:2498) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2498) igt_core-INFO:   #1 ../tests/kms_flip.c:428 run_test_on_crtc_set.constprop.0()
(kms_flip:2498) igt_core-INFO:   #2 ../tests/kms_flip.c:1889 run_test()
(kms_flip:2498) igt_core-INFO:   #3 ../tests/kms_flip.c:2122 __igt_unique____real_main2045()
(kms_flip:2498) igt_core-INFO:   #4 ../tests/kms_flip.c:2045 main()
(kms_flip:2498) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2498) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2498) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest A-HDMI-A1: FAIL (19.014s)
Dmesg
<6> [471.206469] Console: switching to colour dummy device 80x25
<6> [471.207116] [IGT] kms_flip: executing
<7> [471.209272] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [471.210572] i915 0000:03:00.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [471.210908] i915 0000:03:00.0: [drm:i915_gem_open [i915]]
<7> [471.224023] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:DP-1]
<7> [471.224032] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:587:DP-1]
<7> [471.224321] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [471.224504] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [471.224854] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling AUX_A
<7> [471.245941] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.267132] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.288279] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.309404] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.330530] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.351661] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.372785] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.393907] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.415075] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.436233] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.457383] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.478532] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.499681] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.520836] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.541985] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.563136] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.584284] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.605433] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.626584] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.647737] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.668886] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.690033] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.711183] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.732331] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.753464] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.774645] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.795811] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.816966] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.838140] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.859289] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.880449] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.901600] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.901786] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [471.901831] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:DP-1] disconnected
<7> [471.902664] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:HDMI-A-2]
<7> [471.902670] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:606:HDMI-A-2]
<7> [471.906717] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:HDMI-A-2] disconnected
<7> [471.907223] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:610:DP-2]
<7> [471.907228] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:610:DP-2]
<7> [471.911278] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:610:DP-2] disconnected
<7> [471.911746] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:618:HDMI-A-3]
<7> [471.911751] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:618:HDMI-A-3]
<7> [471.915890] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:618:HDMI-A-3] disconnected
<7> [471.916356] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:622:DP-3]
<7> [471.916361] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:622:DP-3]
<7> [471.920393] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:622:DP-3] disconnected
<7> [471.920947] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:630:HDMI-A-4]
<7> [471.920952] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:630:HDMI-A-4]
<7> [471.925013] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:630:HDMI-A-4] disconnected
<7> [471.925812] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:DP-1]
<7> [471.925818] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:587:DP-1]
<7> [471.946963] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.968077] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [471.989233] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.010363] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.031500] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.052682] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.073817] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.094947] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.116106] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.137257] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.158409] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.179560] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.200715] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.221850] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.242997] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.264150] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.285299] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.306447] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.327601] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.348780] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.369949] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.391104] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.412262] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.433414] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.454562] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.475717] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.496866] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.518006] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.539161] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.560317] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.581473] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.602628] i915 0000:03:00.0: [drm:intel_dp_aux_xfer [i915]] AUX A/DDI A/PHY A: timeout (status 0x7d40023f)
<7> [472.602818] i915 0000:03:00.0: [drm:drm_dp_dpcd_access [drm_display_helper]] AUX A/DDI A/PHY A: Too many retries, giving up. First error: -110
<7> [472.602862] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:587:DP-1] disconnected
<7> [472.602884] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:599:HDMI-A-1]
<7> [472.602889] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:599:HDMI-A-1]
<7> [472.627882] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:599:HDMI-A-1] ELD monitor FH-DP4K
<7> [472.627888] i915 0000:03:00.0: [drm:update_display_info.part.0] [CONNECTOR:599:HDMI-A-1] ELD size 36, SAD count 2
<7> [472.628074] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [472.628253] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK on first message, retry
<7> [472.628543] i915 0000:03:00.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus dpa] NAK for addr: 0040 w(1)
<7> [472.628723] i915 0000:03:00.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6)
<7> [472.628736] i915 0000:03:00.0: [drm:intel_hdmi_set_edid [i915]] Assuming DP dual mode adaptor presence based on VBT
<7> [472.628936] 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> [472.629660] 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> [472.629669] 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> [472.629675] 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> [472.629682] 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> [472.629688] 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> [472.629696] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:599:HDMI-A-1] probed modes:
<7> [472.629700] 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> [472.629705] 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> [472.629709] 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> [472.629713] 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> [472.629717] 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> [472.629721] 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> [472.629725] 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> [472.629729] 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> [472.629734] 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> [472.629738] 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> [472.629742] 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> [472.629746] 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> [472.629774] 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> [472.629803] 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> [472.629807] 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> [472.629812] 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> [472.629816] 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> [472.629820] 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> [472.629838] 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> [472.629842] 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> [472.629846] 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> [472.629850] 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> [472.629855] 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> [472.629859] 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> [472.629863] 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> [472.629867] 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> [472.629900] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:HDMI-A-2]
<7> [472.629905] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:606:HDMI-A-2]
<7> [472.634008] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:606:HDMI-A-2] disconnected
<7> [472.634025] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:610:DP-2]
<7> [472.634030] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:610:DP-2]
<7> [472.638084] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:610:DP-2] disconnected
<7> [472.638101] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:618:HDMI-A-3]
<7> [472.638105] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:618:HDMI-A-3]
<7> [472.642159] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:618:HDMI-A-3] disconnected
<7> [472.642177] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:622:DP-3]
<7> [472.642181] i915 0000:03:00.0: [drm:intel_dp_detect [i915]] [CONNECTOR:622:DP-3]
<7> [472.646284] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:622:DP-3] disconnected
<7> [472.646301] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:630:HDMI-A-4]
<7> [472.646305] i915 0000:03:00.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:630:HDMI-A-4]
<7> [472.650375] i915 0000:03:00.0: [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:630:HDMI-A-4] disconnected
<6> [472.651435] [IGT] kms_flip: starting subtest blocking-wf_vblank
<6> [472.657021] [IGT] kms_flip: starting dynamic subtest A-HDMI-A1
<7> [472.657434] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:636]
<7> [472.657550] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:637]
<7> [472.658015] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.658493] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.703997] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling AUX_A
<7> [472.704215] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [472.704416] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [472.704586] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [472.705374] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.705722] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.713751] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.714089] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.732320] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.732655] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [472.758217] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [472.758329] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [472.758509] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [472.758678] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [472.758856] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [472.759068] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [472.759234] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [472.759400] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [472.759566] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [472.759732] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [472.759905] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [472.760071] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [472.760235] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [472.760399] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [472.760565] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [472.760734] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [472.760908] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [472.761076] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [472.761244] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [472.761409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [472.761575] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [472.761739] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [472.761911] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [472.762076] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [472.762243] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [472.762409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [472.762578] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [472.762750] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [472.762917] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [472.763083] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [472.763249] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [472.763414] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [472.763580] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [472.763749] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [472.763914] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [472.764081] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [472.764248] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [472.764416] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [472.764581] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [472.764794] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [472.764986] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [472.765147] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [472.765348] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [472.765509] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [472.765711] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [472.765872] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [472.766034] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [472.766195] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [472.766378] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [472.766541] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [472.766764] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [472.766944] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [472.767110] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [472.767276] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [472.767442] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [472.767649] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [472.767812] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [472.767976] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [472.768140] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [472.768305] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [472.768471] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [472.768702] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [472.768871] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [472.784740] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [472.801405] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [472.801621] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [472.801832] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [472.802030] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [472.802229] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI A/PHY A]
<7> [472.802426] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:588:DP-MST A]
<7> [472.802602] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST B]
<7> [472.802779] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST C]
<7> [472.802956] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST D]
<7> [472.803131] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI B/PHY B]
<7> [472.803316] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DDI TC1/PHY C]
<7> [472.803491] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST A]
<7> [472.803668] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST B]
<7> [472.803845] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST C]
<7> [472.804020] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST D]
<7> [472.804206] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DDI TC2/PHY D]
<7> [472.804384] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DP-MST A]
<7> [472.804560] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST B]
<7> [472.804734] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST C]
<7> [472.804908] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST D]
<7> [472.805082] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:599:HDMI-A-1]
<7> [472.805392] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [472.805650] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [472.806306] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [472.806505] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [472.806690] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [472.806879] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [472.806918] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:599:HDMI-A-1]
<7> [472.807001] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:599:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [472.807201] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [472.807368] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [472.807548] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI A/PHY A] [CRTC:170:pipe A]
<7> [472.807720] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [472.807900] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [472.808086] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [472.808264] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [472.808441] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [472.808618] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [472.808794] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [472.808976] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [472.809154] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [472.809330] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [472.809513] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [472.809690] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [472.809867] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [472.810055] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [472.810231] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [472.810409] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [472.810585] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [472.810763] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [472.810947] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [472.811125] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [472.811302] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [472.811479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [472.811655] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [472.811832] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [472.812014] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [472.812192] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [472.812370] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [472.812545] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [472.812722] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [472.812972] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [472.813151] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [472.813327] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [472.813503] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [472.813680] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [472.813866] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [472.814045] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [472.814221] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [472.814398] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [472.814605] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [472.814785] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [472.815002] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [472.815172] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [472.815346] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [472.815519] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [472.815691] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [472.815871] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [472.816064] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 594000 num active planes 1
<7> [472.816241] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [472.816415] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [472.816608] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [472.816808] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [472.816986] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [472.817165] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [472.817345] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [472.817524] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [472.817708] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [472.817883] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [472.818058] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [472.818234] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [472.818410] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [472.818594] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [472.818771] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [472.818946] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [472.819123] 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> [472.819299] 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> [472.819474] 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> [472.819659] 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> [472.819836] 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> [472.820014] 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> [472.820193] 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> [472.820370] 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> [472.820554] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [472.820732] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [472.820906] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [472.821081] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [472.821258] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [472.821435] 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> [472.821618] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [472.821797] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [472.821973] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [472.822150] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.822325] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.822510] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.822687] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [472.822864] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [472.823042] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.823218] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.823394] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [472.823578] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [472.823753] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [472.823930] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [472.824109] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [472.824287] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [472.824470] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [472.824648] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [472.824823] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [472.825001] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [472.825175] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [472.825358] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [472.825533] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [472.825997] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI A/PHY A]
<7> [472.826174] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:588:DP-MST A]
<7> [472.826361] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST B]
<7> [472.826536] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST C]
<7> [472.826709] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST D]
<7> [472.826886] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI B/PHY B]
<7> [472.827059] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DDI TC1/PHY C]
<7> [472.827234] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST A]
<7> [472.827414] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST B]
<7> [472.827592] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST C]
<7> [472.827766] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST D]
<7> [472.827940] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DDI TC2/PHY D]
<7> [472.828114] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DP-MST A]
<7> [472.828296] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST B]
<7> [472.828470] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST C]
<7> [472.828645] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST D]
<7> [472.828903] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [472.829259] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [472.829438] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [472.829668] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [472.829924] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [472.830523] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [472.830704] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [472.847515] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:599:HDMI-A-1]
<7> [472.847799] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [472.904523] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [472.904763] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [472.904974] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [473.133715] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [473.134670] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [473.165627] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [473.166636] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [473.167596] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [483.470668] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [483.471249] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [483.472258] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [483.473196] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [483.474152] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 4, found 0)
<7> [483.475085] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [483.476046] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [483.476511] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [483.476709] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [483.476899] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [483.477059] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [483.477219] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [483.477383] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [483.477544] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [483.477745] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [483.477938] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [483.478098] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [483.478259] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [483.478419] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [483.478578] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [483.478781] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [483.478971] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [483.479131] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [483.479293] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [483.479453] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [483.479642] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [483.479846] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [483.480006] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [483.480166] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [483.480356] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [483.480519] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [483.480766] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [483.480943] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [483.481106] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [483.481268] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [483.481458] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [483.481703] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [483.481881] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [483.482042] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [483.482203] i915 0000:03:00.0: [drm:intel_dpll_crtc_put [i915]] [CRTC:170:pipe A] releasing DPLL 0
<7> [483.482414] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [483.482591] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0
<7> [483.482807] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [483.482975] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [483.483145] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [483.483313] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [483.483484] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [483.483661] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [483.483851] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 0 num active planes 0
<7> [483.484022] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 0
<7> [483.484212] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 74250 kHz -> 0 kHz
<7> [483.484402] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [483.484587] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: no [modeset]
<7> [483.484761] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [NOFB], visible: no
<7> [483.484934] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [483.485108] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [483.485281] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [483.485455] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [483.485636] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [483.485810] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [483.485981] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [483.486221] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [483.486399] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [483.497355] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [483.514453] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_A
<7> [483.514667] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:170:pipe A]
<7> [483.514871] i915 0000:03:00.0: [drm:intel_dpll_disable [i915]] disabling DPLL 0
<7> [483.515066] i915 0000:03:00.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:34:plane 1A]
<7> [483.515247] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI A/PHY A]
<7> [483.515452] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:588:DP-MST A]
<7> [483.515623] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST B]
<7> [483.515793] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST C]
<7> [483.515962] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST D]
<7> [483.516132] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI B/PHY B]
<7> [483.516311] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DDI TC1/PHY C]
<7> [483.516482] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST A]
<7> [483.516654] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST B]
<7> [483.516824] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST C]
<7> [483.516996] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST D]
<7> [483.517165] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DDI TC2/PHY D]
<7> [483.517345] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DP-MST A]
<7> [483.517517] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST B]
<7> [483.517691] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST C]
<7> [483.517863] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST D]
<7> [483.518034] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:599:HDMI-A-1]
<7> [483.518337] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [483.518592] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [483.519227] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:308:pipe B]
<7> [483.519441] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:446:pipe C]
<7> [483.519627] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:584:pipe D]
<7> [483.519814] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:170:pipe A]
<7> [483.519855] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:599:HDMI-A-1]
<7> [483.519937] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CONNECTOR:599:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [483.520121] i915 0000:03:00.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [483.520295] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [483.520470] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:586:DDI A/PHY A] [CRTC:170:pipe A]
<7> [483.520636] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [483.520812] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [483.520984] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [483.521159] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [483.521339] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [483.521513] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [483.521685] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [483.521856] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [483.522030] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [483.522203] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [483.522387] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [483.522560] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [483.522734] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [483.522905] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [483.523079] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [483.523262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [483.523435] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [483.523609] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [483.523780] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [483.523952] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [483.524124] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [483.524374] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [483.524551] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [483.524724] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [483.524896] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [483.525070] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [483.525253] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [483.525428] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [483.525600] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [483.525772] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [483.525944] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [483.526118] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [483.526298] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [483.526471] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [483.526644] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [483.526818] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [483.526991] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:170:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [483.527166] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:170:pipe A] fastset requirement not met, forcing full modeset
<7> [483.527373] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [483.527541] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:170:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1
<7> [483.527757] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:34:plane 1A] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [483.527926] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [PLANE:164:cursor A] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [483.528094] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [483.528270] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [483.528440] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34: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> [483.528607] i915 0000:03:00.0: [drm:skl_print_plane_changes [i915]] [PLANE:34:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 91, 91, 91, 143, 143, 161, 179, 31, 0, 0
<7> [483.528794] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:170:pipe A] data rate 594000 num active planes 1
<7> [483.528966] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 34000 required 594
<7> [483.529136] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [483.529332] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:170:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [483.529521] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [483.529695] i915 0000:03:00.0: [drm:intel_find_dpll [i915]] [CRTC:170:pipe A] allocated DPLL 0
<7> [483.529870] i915 0000:03:00.0: [drm:intel_dpll_crtc_get [i915]] [CRTC:170:pipe A] reserving DPLL 0
<7> [483.530048] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:170:pipe A] enable: yes [modeset]
<7> [483.530227] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [483.530398] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [483.530571] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [483.530742] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [483.530912] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [483.531085] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [483.531263] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [483.531436] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [483.531609] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [483.531781] 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> [483.531956] 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> [483.532137] 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> [483.532312] 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> [483.532492] 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> [483.532653] 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> [483.532830] 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> [483.533005] 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> [483.533238] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [483.533416] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [483.533576] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [483.533737] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [483.533942] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [483.534131] 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> [483.534352] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [483.534540] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [483.534701] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [483.534863] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.535024] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.535257] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.535434] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [483.535594] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [483.535756] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.535917] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.536118] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [483.536312] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [483.536472] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [483.536633] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:plane 1A] fb: [FB:636] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [483.536794] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [483.536955] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [483.537181] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:64:plane 2A] fb: [NOFB], visible: no
<7> [483.537413] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:94:plane 3A] fb: [NOFB], visible: no
<7> [483.537575] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:124:plane 4A] fb: [NOFB], visible: no
<7> [483.537735] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:134:plane 5A] fb: [NOFB], visible: no
<7> [483.537894] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:144:plane 6A] fb: [NOFB], visible: no
<7> [483.538097] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:154:plane 7A] fb: [NOFB], visible: no
<7> [483.538289] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:164:cursor A] fb: [NOFB], visible: no
<7> [483.538567] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:586:DDI A/PHY A]
<7> [483.538727] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:588:DP-MST A]
<7> [483.538903] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:589:DP-MST B]
<7> [483.539094] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:590:DP-MST C]
<7> [483.539311] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:591:DP-MST D]
<7> [483.539471] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:605:DDI B/PHY B]
<7> [483.539661] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:609:DDI TC1/PHY C]
<7> [483.539820] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:611:DP-MST A]
<7> [483.539981] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:612:DP-MST B]
<7> [483.540210] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:613:DP-MST C]
<7> [483.540388] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:614:DP-MST D]
<7> [483.540548] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:621:DDI TC2/PHY D]
<7> [483.540765] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:623:DP-MST A]
<7> [483.540925] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:624:DP-MST B]
<7> [483.541157] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:625:DP-MST C]
<7> [483.541332] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:626:DP-MST D]
<7> [483.541600] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [483.541941] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:170:pipe A]
<7> [483.542177] i915 0000:03:00.0: [drm:intel_dpll_enable [i915]] enabling DPLL 0
<7> [483.542406] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_A
<7> [483.542675] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [483.543315] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [483.543479] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:34:plane 1A]
<7> [483.560287] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:599:HDMI-A-1]
<7> [483.560564] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:170:pipe A]
<7> [483.623668] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [483.624706] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [483.625678] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [483.845809] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [483.846780] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [483.878182] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [483.879187] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [483.880184] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<6> [491.674661] [IGT] kms_flip: finished subtest A-HDMI-A1, FAIL
Created at 2026-02-03 22:42:26