Results for igt@kms_flip@wf_vblank-ts-check-interruptible@d-hdmi-a3

Result: Fail

integration-manifest git-log-oneline i915_display_info10 igt_runner10 runtimes10 results10.json results10-i915-load.json guc_logs10.tar i915_display_info_post_exec10 boot10 dmesg10

DetailValue
Duration 18.83 seconds
Hostname 
shard-dg2-5
Igt-Version 
IGT-Version: 2.4-ga5c5d6558 (x86_64) (Linux: 7.0.0-CI_DRM_18365-gf3450dfabc8a+ x86_64)
Out 
Starting dynamic subtest: D-HDMI-A3
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16676.0us +- 3.899us accuracy 0.07%
vblank interval differs from modeline! expected 16666.7us, measured 16676us +- 3.899us, difference 9.3us (2.4 sigma)
Event vblank: expected 456, counted 450, passrate = 62.22%, encoder type 2
Expected frametime: 16667us; measured 16992.9us +- 5.875us accuracy 0.10%
vblank interval differs from modeline! expected 16666.7us, measured 16993us +- 5.875us, difference 326.2us (55.5 sigma)
Event vblank: expected 447, counted 450, passrate = 35.56%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
  #2 ../tests/kms_flip.c:1920 run_test()
  #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
  #4 ../tests/kms_flip.c:2105 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest D-HDMI-A3: FAIL (18.829s)
Subtest wf_vblank-ts-check-interruptible: FAIL (42.775s)
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../lib/igt_kms.c:2352 kmstest_set_connector_dpms()
  #2 ../lib/igt_core.c:2961 igt_atexit_handler()
  #3 [erand48_r+0x1d6]
  #4 [exit+0x1e]
  #5 ../lib/igt_core.c:2416 igt_exit()
  #6 [main+0x3f]
  #7 [__libc_init_first+0x8a]
  #8 [__libc_start_main+0x8b]
  #9 [_start+0x25]
Err 
Starting dynamic subtest: D-HDMI-A3
(kms_flip:2396) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2396) CRITICAL: Failed assertion: !retried
(kms_flip:2396) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest D-HDMI-A3 failed.
**** DEBUG ****
(kms_flip:2396) intel_allocator_reloc-DEBUG: Using reloc allocator
(kms_flip:2396) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2396) intel_blt-DEBUG: Test requirement passed: cmds_info
(kms_flip:2396) igt_kms-INFO:   1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
(kms_flip:2396) DEBUG: No stale events found
(kms_flip:2396) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2396) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2396) INFO: Expected frametime: 16667us; measured 16676.0us +- 3.899us accuracy 0.07%
(kms_flip:2396) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16676us +- 3.899us, difference 9.3us (2.4 sigma)
(kms_flip:2396) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 341.695801
current_received_ts = 341.695312
current_seq = 58
count = 0
seq_step = 10
(kms_flip:2396) DEBUG: name = vblank
last_ts = 341.695801
last_received_ts = 341.695312
last_seq = 58
current_ts = 341.862549
current_received_ts = 341.862152
current_seq = 68
count = 1
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 341.695801/58, current 341.862549/68: elapsed=166746.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 341.862549
last_received_ts = 341.862152
last_seq = 68
current_ts = 342.029297
current_received_ts = 342.028900
current_seq = 78
count = 2
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 341.862549/68, current 342.029297/78: elapsed=166758.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.029297
last_received_ts = 342.028900
last_seq = 78
current_ts = 342.196075
current_received_ts = 342.195679
current_seq = 88
count = 3
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.029297/78, current 342.196075/88: elapsed=166759.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.196075
last_received_ts = 342.195679
last_seq = 88
current_ts = 342.362823
current_received_ts = 342.362366
current_seq = 98
count = 4
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.196075/88, current 342.362823/98: elapsed=166757.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.362823
last_received_ts = 342.362366
last_seq = 98
current_ts = 342.529572
current_received_ts = 342.529083
current_seq = 108
count = 5
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.362823/98, current 342.529572/108: elapsed=166763.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.529572
last_received_ts = 342.529083
last_seq = 108
current_ts = 342.696320
current_received_ts = 342.695862
current_seq = 118
count = 6
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.529572/108, current 342.696320/118: elapsed=166747.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.696320
last_received_ts = 342.695862
last_seq = 118
current_ts = 342.863098
current_received_ts = 342.862671
current_seq = 128
count = 7
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.696320/118, current 342.863098/128: elapsed=166764.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 342.863098
last_received_ts = 342.862671
last_seq = 128
current_ts = 343.029846
current_received_ts = 343.029419
current_seq = 138
count = 8
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 342.863098/128, current 343.029846/138: elapsed=166752.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.029846
last_received_ts = 343.029419
last_seq = 138
current_ts = 343.196625
current_received_ts = 343.196167
current_seq = 148
count = 9
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.029846/138, current 343.196625/148: elapsed=166764.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.196625
last_received_ts = 343.196167
last_seq = 148
current_ts = 343.363373
current_received_ts = 343.362946
current_seq = 158
count = 10
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.196625/148, current 343.363373/158: elapsed=166752.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.363373
last_received_ts = 343.362946
last_seq = 158
current_ts = 343.530121
current_received_ts = 343.529694
current_seq = 168
count = 11
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.363373/158, current 343.530121/168: elapsed=166757.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.530121
last_received_ts = 343.529694
last_seq = 168
current_ts = 343.696869
current_received_ts = 343.696411
current_seq = 178
count = 12
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.530121/168, current 343.696869/178: elapsed=166761.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.696869
last_received_ts = 343.696411
last_seq = 178
current_ts = 343.863617
current_received_ts = 343.863220
current_seq = 188
count = 13
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.696869/178, current 343.863617/188: elapsed=166745.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 343.863617
last_received_ts = 343.863220
last_seq = 188
current_ts = 344.030396
current_received_ts = 344.029968
current_seq = 198
count = 14
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 343.863617/188, current 344.030396/198: elapsed=166763.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.030396
last_received_ts = 344.029968
last_seq = 198
current_ts = 344.197144
current_received_ts = 344.196716
current_seq = 208
count = 15
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.030396/198, current 344.197144/208: elapsed=166757.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.197144
last_received_ts = 344.196716
last_seq = 208
current_ts = 344.363892
current_received_ts = 344.363495
current_seq = 218
count = 16
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.197144/208, current 344.363892/218: elapsed=166754.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.363892
last_received_ts = 344.363495
last_seq = 218
current_ts = 344.530670
current_received_ts = 344.530273
current_seq = 228
count = 17
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.363892/218, current 344.530670/228: elapsed=166760.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.530670
last_received_ts = 344.530273
last_seq = 228
current_ts = 344.697418
current_received_ts = 344.696991
current_seq = 238
count = 18
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.530670/228, current 344.697418/238: elapsed=166753.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.697418
last_received_ts = 344.696991
last_seq = 238
current_ts = 344.864166
current_received_ts = 344.863739
current_seq = 248
count = 19
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.697418/238, current 344.864166/248: elapsed=166759.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 344.864166
last_received_ts = 344.863739
last_seq = 248
current_ts = 345.030914
current_received_ts = 345.030518
current_seq = 258
count = 20
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 344.864166/248, current 345.030914/258: elapsed=166749.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.030914
last_received_ts = 345.030518
last_seq = 258
current_ts = 345.197693
current_received_ts = 345.197205
current_seq = 268
count = 21
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.030914/258, current 345.197693/268: elapsed=166766.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.197693
last_received_ts = 345.197205
last_seq = 268
current_ts = 345.364441
current_received_ts = 345.364044
current_seq = 278
count = 22
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.197693/268, current 345.364441/278: elapsed=166745.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.364441
last_received_ts = 345.364044
last_seq = 278
current_ts = 345.531189
current_received_ts = 345.530762
current_seq = 288
count = 23
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.364441/278, current 345.531189/288: elapsed=166760.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.531189
last_received_ts = 345.530762
last_seq = 288
current_ts = 345.697937
current_received_ts = 345.697540
current_seq = 298
count = 24
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.531189/288, current 345.697937/298: elapsed=166756.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.697937
last_received_ts = 345.697540
last_seq = 298
current_ts = 345.864716
current_received_ts = 345.864258
current_seq = 308
count = 25
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.697937/298, current 345.864716/308: elapsed=166760.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 345.864716
last_received_ts = 345.864258
last_seq = 308
current_ts = 346.031464
current_received_ts = 346.031097
current_seq = 318
count = 26
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 345.864716/308, current 346.031464/318: elapsed=166752.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.031464
last_received_ts = 346.031097
last_seq = 318
current_ts = 346.198212
current_received_ts = 346.197754
current_seq = 328
count = 27
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.031464/318, current 346.198212/328: elapsed=166757.0us expected=166760.0us +- 833.8us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.198212
last_received_ts = 346.197754
last_seq = 328
current_ts = 346.369781
current_received_ts = 346.369293
current_seq = 338
count = 28
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.198212/328, current 346.369781/338: elapsed=171553.0us expected=166760.0us +- 833.8us, error 2.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 346.198212/328, current 346.369781/338: elapsed=171553.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.369781
last_received_ts = 346.369293
last_seq = 338
current_ts = 346.543610
current_received_ts = 346.543182
current_seq = 348
count = 29
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.369781/338, current 346.543610/348: elapsed=173833.0us expected=166760.0us +- 833.8us, error 4.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 346.369781/338, current 346.543610/348: elapsed=173833.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.543610
last_received_ts = 346.543182
last_seq = 348
current_ts = 346.717407
current_received_ts = 346.716888
current_seq = 358
count = 30
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.543610/348, current 346.717407/358: elapsed=173812.0us expected=166760.0us +- 833.8us, error 4.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 346.543610/348, current 346.717407/358: elapsed=173812.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.717407
last_received_ts = 346.716888
last_seq = 358
current_ts = 346.891235
current_received_ts = 346.890747
current_seq = 368
count = 31
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.717407/358, current 346.891235/368: elapsed=173832.0us expected=166760.0us +- 833.8us, error 4.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 346.717407/358, current 346.891235/368: elapsed=173832.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 346.891235
last_received_ts = 346.890747
last_seq = 368
current_ts = 347.065063
current_received_ts = 347.064514
current_seq = 378
count = 32
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 346.891235/368, current 347.065063/378: elapsed=173813.0us expected=166760.0us +- 833.8us, error 4.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 346.891235/368, current 347.065063/378: elapsed=173813.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.065063
last_received_ts = 347.064514
last_seq = 378
current_ts = 347.238892
current_received_ts = 347.238342
current_seq = 388
count = 33
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.065063/378, current 347.238892/388: elapsed=173822.0us expected=166760.0us +- 833.8us, error 4.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.065063/378, current 347.238892/388: elapsed=173822.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.238892
last_received_ts = 347.238342
last_seq = 388
current_ts = 347.411896
current_received_ts = 347.411469
current_seq = 398
count = 34
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.238892/388, current 347.411896/398: elapsed=173018.0us expected=166760.0us +- 833.8us, error 3.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.238892/388, current 347.411896/398: elapsed=173018.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.411896
last_received_ts = 347.411469
last_seq = 398
current_ts = 347.584839
current_received_ts = 347.584412
current_seq = 408
count = 35
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.411896/398, current 347.584839/408: elapsed=172934.0us expected=166760.0us +- 833.8us, error 3.7%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.411896/398, current 347.584839/408: elapsed=172934.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.584839
last_received_ts = 347.584412
last_seq = 408
current_ts = 347.757782
current_received_ts = 347.757294
current_seq = 418
count = 36
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.584839/408, current 347.757782/418: elapsed=172935.0us expected=166760.0us +- 833.8us, error 3.7%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.584839/408, current 347.757782/418: elapsed=172935.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.757782
last_received_ts = 347.757294
last_seq = 418
current_ts = 347.930725
current_received_ts = 347.930298
current_seq = 428
count = 37
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.757782/418, current 347.930725/428: elapsed=172946.0us expected=166760.0us +- 833.8us, error 3.7%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.757782/418, current 347.930725/428: elapsed=172946.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 347.930725
last_received_ts = 347.930298
last_seq = 428
current_ts = 348.103668
current_received_ts = 348.103180
current_seq = 438
count = 38
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 347.930725/428, current 348.103668/438: elapsed=172942.0us expected=166760.0us +- 833.8us, error 3.7%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 347.930725/428, current 348.103668/438: elapsed=172942.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.103668
last_received_ts = 348.103180
last_seq = 438
current_ts = 348.276489
current_received_ts = 348.276093
current_seq = 448
count = 39
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.103668/438, current 348.276489/448: elapsed=172826.0us expected=166760.0us +- 833.8us, error 3.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.103668/438, current 348.276489/448: elapsed=172826.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.276489
last_received_ts = 348.276093
last_seq = 448
current_ts = 348.448669
current_received_ts = 348.448212
current_seq = 458
count = 40
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.276489/448, current 348.448669/458: elapsed=172171.0us expected=166760.0us +- 833.8us, error 3.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.276489/448, current 348.448669/458: elapsed=172171.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.448669
last_received_ts = 348.448212
last_seq = 458
current_ts = 348.620819
current_received_ts = 348.620270
current_seq = 468
count = 41
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.448669/458, current 348.620819/468: elapsed=172164.0us expected=166760.0us +- 833.8us, error 3.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.448669/458, current 348.620819/468: elapsed=172164.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.620819
last_received_ts = 348.620270
last_seq = 468
current_ts = 348.792969
current_received_ts = 348.792572
current_seq = 478
count = 42
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.620819/468, current 348.792969/478: elapsed=172164.0us expected=166760.0us +- 833.8us, error 3.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.620819/468, current 348.792969/478: elapsed=172164.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.792969
last_received_ts = 348.792572
last_seq = 478
current_ts = 348.965149
current_received_ts = 348.964722
current_seq = 488
count = 43
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.792969/478, current 348.965149/488: elapsed=172169.0us expected=166760.0us +- 833.8us, error 3.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.792969/478, current 348.965149/488: elapsed=172169.0us expected=166760.0us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 348.965149
last_received_ts = 348.964722
last_seq = 488
current_ts = 349.137329
current_received_ts = 349.136902
current_seq = 498
count = 44
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 348.965149/488, current 349.137329/498: elapsed=172167.0us expected=166760.0us +- 833.8us, error 3.2%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 348.965149/488, current 349.137329/498: elapsed=172167.0us expected=166760.0us
(kms_flip:2396) INFO: Event vblank: expected 456, counted 450, passrate = 62.22%, encoder type 2
(kms_flip:2396) DEBUG: dropped frames, expected 456, counted 450, passrate = 62.22%, encoder type 2
(kms_flip:2396) DEBUG: Retrying without a hotplug event
(kms_flip:2396) DEBUG: No stale events found
(kms_flip:2396) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:2396) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:2396) INFO: Expected frametime: 16667us; measured 16992.9us +- 5.875us accuracy 0.10%
(kms_flip:2396) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16993us +- 5.875us, difference 326.2us (55.5 sigma)
(kms_flip:2396) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 352.712708
current_received_ts = 352.712280
current_seq = 529
count = 0
seq_step = 10
(kms_flip:2396) DEBUG: name = vblank
last_ts = 352.712708
last_received_ts = 352.712280
last_seq = 529
current_ts = 352.882629
current_received_ts = 352.882172
current_seq = 539
count = 1
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 352.712708/529, current 352.882629/539: elapsed=169924.0us expected=169928.8us +- 849.6us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 352.882629
last_received_ts = 352.882172
last_seq = 539
current_ts = 353.052582
current_received_ts = 353.052155
current_seq = 549
count = 2
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 352.882629/539, current 353.052582/549: elapsed=169931.0us expected=169928.8us +- 849.6us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.052582
last_received_ts = 353.052155
last_seq = 549
current_ts = 353.222504
current_received_ts = 353.222107
current_seq = 559
count = 3
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.052582/549, current 353.222504/559: elapsed=169929.0us expected=169928.8us +- 849.6us, error 0.0%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.222504
last_received_ts = 353.222107
last_seq = 559
current_ts = 353.392090
current_received_ts = 353.391663
current_seq = 569
count = 4
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.222504/559, current 353.392090/569: elapsed=169596.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.392090
last_received_ts = 353.391663
last_seq = 569
current_ts = 353.561646
current_received_ts = 353.561218
current_seq = 579
count = 5
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.392090/569, current 353.561646/579: elapsed=169533.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.561646
last_received_ts = 353.561218
last_seq = 579
current_ts = 353.731140
current_received_ts = 353.730743
current_seq = 589
count = 6
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.561646/579, current 353.731140/589: elapsed=169522.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.731140
last_received_ts = 353.730743
last_seq = 589
current_ts = 353.900696
current_received_ts = 353.900269
current_seq = 599
count = 7
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.731140/589, current 353.900696/599: elapsed=169541.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 353.900696
last_received_ts = 353.900269
last_seq = 599
current_ts = 354.070221
current_received_ts = 354.069794
current_seq = 609
count = 8
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 353.900696/599, current 354.070221/609: elapsed=169526.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.070221
last_received_ts = 354.069794
last_seq = 609
current_ts = 354.239746
current_received_ts = 354.239258
current_seq = 619
count = 9
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.070221/609, current 354.239746/619: elapsed=169527.0us expected=169928.8us +- 849.6us, error 0.2%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.239746
last_received_ts = 354.239258
last_seq = 619
current_ts = 354.408966
current_received_ts = 354.408539
current_seq = 629
count = 10
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.239746/619, current 354.408966/629: elapsed=169207.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.408966
last_received_ts = 354.408539
last_seq = 629
current_ts = 354.578125
current_received_ts = 354.577728
current_seq = 639
count = 11
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.408966/629, current 354.578125/639: elapsed=169185.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.578125
last_received_ts = 354.577728
last_seq = 639
current_ts = 354.747314
current_received_ts = 354.746796
current_seq = 649
count = 12
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.578125/639, current 354.747314/649: elapsed=169186.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.747314
last_received_ts = 354.746796
last_seq = 649
current_ts = 354.916504
current_received_ts = 354.916107
current_seq = 659
count = 13
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.747314/649, current 354.916504/659: elapsed=169178.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 354.916504
last_received_ts = 354.916107
last_seq = 659
current_ts = 355.085693
current_received_ts = 355.085266
current_seq = 669
count = 14
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 354.916504/659, current 355.085693/669: elapsed=169187.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.085693
last_received_ts = 355.085266
last_seq = 669
current_ts = 355.254883
current_received_ts = 355.254425
current_seq = 679
count = 15
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.085693/669, current 355.254883/679: elapsed=169178.0us expected=169928.8us +- 849.6us, error 0.4%
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.254883
last_received_ts = 355.254425
last_seq = 679
current_ts = 355.423737
current_received_ts = 355.423248
current_seq = 689
count = 16
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.254883/679, current 355.423737/689: elapsed=168872.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 355.254883/679, current 355.423737/689: elapsed=168872.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.423737
last_received_ts = 355.423248
last_seq = 689
current_ts = 355.592621
current_received_ts = 355.592163
current_seq = 699
count = 17
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.423737/689, current 355.592621/699: elapsed=168889.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 355.423737/689, current 355.592621/699: elapsed=168889.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.592621
last_received_ts = 355.592163
last_seq = 699
current_ts = 355.761505
current_received_ts = 355.761108
current_seq = 709
count = 18
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.592621/699, current 355.761505/709: elapsed=168875.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 355.592621/699, current 355.761505/709: elapsed=168875.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.761505
last_received_ts = 355.761108
last_seq = 709
current_ts = 355.930389
current_received_ts = 355.929962
current_seq = 719
count = 19
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.761505/709, current 355.930389/719: elapsed=168876.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 355.761505/709, current 355.930389/719: elapsed=168876.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 355.930389
last_received_ts = 355.929962
last_seq = 719
current_ts = 356.099274
current_received_ts = 356.098846
current_seq = 729
count = 20
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 355.930389/719, current 356.099274/729: elapsed=168884.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 355.930389/719, current 356.099274/729: elapsed=168884.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.099274
last_received_ts = 356.098846
last_seq = 729
current_ts = 356.268097
current_received_ts = 356.267670
current_seq = 739
count = 21
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.099274/729, current 356.268097/739: elapsed=168843.0us expected=169928.8us +- 849.6us, error 0.6%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.099274/729, current 356.268097/739: elapsed=168843.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.268097
last_received_ts = 356.267670
last_seq = 739
current_ts = 356.436737
current_received_ts = 356.436279
current_seq = 749
count = 22
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.268097/739, current 356.436737/749: elapsed=168619.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.268097/739, current 356.436737/749: elapsed=168619.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.436737
last_received_ts = 356.436279
last_seq = 749
current_ts = 356.605347
current_received_ts = 356.604919
current_seq = 759
count = 23
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.436737/749, current 356.605347/759: elapsed=168617.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.436737/749, current 356.605347/759: elapsed=168617.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.605347
last_received_ts = 356.604919
last_seq = 759
current_ts = 356.773956
current_received_ts = 356.773438
current_seq = 769
count = 24
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.605347/759, current 356.773956/769: elapsed=168604.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.605347/759, current 356.773956/769: elapsed=168604.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.773956
last_received_ts = 356.773438
last_seq = 769
current_ts = 356.942566
current_received_ts = 356.942169
current_seq = 779
count = 25
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.773956/769, current 356.942566/779: elapsed=168617.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.773956/769, current 356.942566/779: elapsed=168617.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 356.942566
last_received_ts = 356.942169
last_seq = 779
current_ts = 357.111176
current_received_ts = 357.110748
current_seq = 789
count = 26
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 356.942566/779, current 357.111176/789: elapsed=168617.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 356.942566/779, current 357.111176/789: elapsed=168617.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.111176
last_received_ts = 357.110748
last_seq = 789
current_ts = 357.279755
current_received_ts = 357.279266
current_seq = 799
count = 27
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.111176/789, current 357.279755/799: elapsed=168569.0us expected=169928.8us +- 849.6us, error 0.8%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.111176/789, current 357.279755/799: elapsed=168569.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.279755
last_received_ts = 357.279266
last_seq = 799
current_ts = 357.448151
current_received_ts = 357.447723
current_seq = 809
count = 28
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.279755/799, current 357.448151/809: elapsed=168386.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.279755/799, current 357.448151/809: elapsed=168386.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.448151
last_received_ts = 357.447723
last_seq = 809
current_ts = 357.616516
current_received_ts = 357.616119
current_seq = 819
count = 29
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.448151/809, current 357.616516/819: elapsed=168377.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.448151/809, current 357.616516/819: elapsed=168377.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.616516
last_received_ts = 357.616119
last_seq = 819
current_ts = 357.784882
current_received_ts = 357.784485
current_seq = 829
count = 30
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.616516/819, current 357.784882/829: elapsed=168383.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.616516/819, current 357.784882/829: elapsed=168383.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.784882
last_received_ts = 357.784485
last_seq = 829
current_ts = 357.953278
current_received_ts = 357.952850
current_seq = 839
count = 31
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.784882/829, current 357.953278/839: elapsed=168384.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.784882/829, current 357.953278/839: elapsed=168384.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 357.953278
last_received_ts = 357.952850
last_seq = 839
current_ts = 358.121674
current_received_ts = 358.121246
current_seq = 849
count = 32
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 357.953278/839, current 358.121674/849: elapsed=168388.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 357.953278/839, current 358.121674/849: elapsed=168388.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.121674
last_received_ts = 358.121246
last_seq = 849
current_ts = 358.289978
current_received_ts = 358.289581
current_seq = 859
count = 33
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.121674/849, current 358.289978/859: elapsed=168325.0us expected=169928.8us +- 849.6us, error 0.9%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.121674/849, current 358.289978/859: elapsed=168325.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.289978
last_received_ts = 358.289581
last_seq = 859
current_ts = 358.458160
current_received_ts = 358.457733
current_seq = 869
count = 34
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.289978/859, current 358.458160/869: elapsed=168180.0us expected=169928.8us +- 849.6us, error 1.0%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.289978/859, current 358.458160/869: elapsed=168180.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.458160
last_received_ts = 358.457733
last_seq = 869
current_ts = 358.626343
current_received_ts = 358.625885
current_seq = 879
count = 35
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.458160/869, current 358.626343/879: elapsed=168180.0us expected=169928.8us +- 849.6us, error 1.0%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.458160/869, current 358.626343/879: elapsed=168180.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.626343
last_received_ts = 358.625885
last_seq = 879
current_ts = 358.794525
current_received_ts = 358.794128
current_seq = 889
count = 36
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.626343/879, current 358.794525/889: elapsed=168176.0us expected=169928.8us +- 849.6us, error 1.0%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.626343/879, current 358.794525/889: elapsed=168176.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.794525
last_received_ts = 358.794128
last_seq = 889
current_ts = 358.962708
current_received_ts = 358.962280
current_seq = 899
count = 37
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.794525/889, current 358.962708/899: elapsed=168182.0us expected=169928.8us +- 849.6us, error 1.0%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.794525/889, current 358.962708/899: elapsed=168182.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 358.962708
last_received_ts = 358.962280
last_seq = 899
current_ts = 359.130890
current_received_ts = 359.130432
current_seq = 909
count = 38
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 358.962708/899, current 359.130890/909: elapsed=168175.0us expected=169928.8us +- 849.6us, error 1.0%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 358.962708/899, current 359.130890/909: elapsed=168175.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.130890
last_received_ts = 359.130432
last_seq = 909
current_ts = 359.299011
current_received_ts = 359.298615
current_seq = 919
count = 39
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.130890/909, current 359.299011/919: elapsed=168125.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.130890/909, current 359.299011/919: elapsed=168125.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.299011
last_received_ts = 359.298615
last_seq = 919
current_ts = 359.467010
current_received_ts = 359.466583
current_seq = 929
count = 40
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.299011/919, current 359.467010/929: elapsed=168000.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.299011/919, current 359.467010/929: elapsed=168000.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.467010
last_received_ts = 359.466583
last_seq = 929
current_ts = 359.635010
current_received_ts = 359.634583
current_seq = 939
count = 41
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.467010/929, current 359.635010/939: elapsed=168000.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.467010/929, current 359.635010/939: elapsed=168000.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.635010
last_received_ts = 359.634583
last_seq = 939
current_ts = 359.803009
current_received_ts = 359.802490
current_seq = 949
count = 42
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.635010/939, current 359.803009/949: elapsed=167993.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.635010/939, current 359.803009/949: elapsed=167993.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.803009
last_received_ts = 359.802490
last_seq = 949
current_ts = 359.971008
current_received_ts = 359.970581
current_seq = 959
count = 43
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.803009/949, current 359.971008/959: elapsed=168008.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.803009/949, current 359.971008/959: elapsed=168008.0us expected=169928.8us
(kms_flip:2396) DEBUG: name = vblank
last_ts = 359.971008
last_received_ts = 359.970581
last_seq = 959
current_ts = 360.139008
current_received_ts = 360.138580
current_seq = 969
count = 44
seq_step = 10
(kms_flip:2396) DEBUG: vblank ts/seq: last 359.971008/959, current 360.139008/969: elapsed=167999.0us expected=169928.8us +- 849.6us, error 1.1%
(kms_flip:2396) DEBUG: inconsistent vblank ts/seq: last 359.971008/959, current 360.139008/969: elapsed=167999.0us expected=169928.8us
(kms_flip:2396) INFO: Event vblank: expected 447, counted 450, passrate = 35.56%, encoder type 2
(kms_flip:2396) DEBUG: dropped frames, expected 447, counted 450, passrate = 35.56%, encoder type 2
(kms_flip:2396) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1722:
(kms_flip:2396) CRITICAL: Failed assertion: !retried
(kms_flip:2396) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:2396) igt_core-INFO: Stack trace:
(kms_flip:2396) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:2396) igt_core-INFO:   #1 ../tests/kms_flip.c:1071 run_test_on_crtc_set()
(kms_flip:2396) igt_core-INFO:   #2 ../tests/kms_flip.c:1920 run_test()
(kms_flip:2396) igt_core-INFO:   #3 ../tests/kms_flip.c:2220 __igt_unique____real_main2105()
(kms_flip:2396) igt_core-INFO:   #4 ../tests/kms_flip.c:2105 main()
(kms_flip:2396) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:2396) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:2396) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest D-HDMI-A3: FAIL (18.829s)
Subtest wf_vblank-ts-check-interruptible: FAIL (42.775s)
(kms_flip:2396) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2352:
(kms_flip:2396) igt_kms-CRITICAL: Failed assertion: found_it
(kms_flip:2396) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor
(kms_flip:2396) igt_kms-CRITICAL: DPMS property not found on 556
Dmesg
<6> [340.615336] [IGT] kms_flip: starting dynamic subtest D-HDMI-A3
<7> [340.615778] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:562]
<7> [340.615945] i915 0000:03:00.0: [drm:drm_mode_addfb2] [FB:563]
<7> [340.616365] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.616880] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.638653] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.639103] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.646378] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.646736] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.668658] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.669042] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [340.675087] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [340.675263] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [340.675413] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [340.675497] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [340.675729] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [340.675930] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [340.676192] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [340.676420] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [340.676618] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [340.676845] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [340.677127] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [340.677376] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [340.677603] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [340.677801] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [340.678038] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [340.678251] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [340.678478] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [340.678675] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [340.678874] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [340.679238] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [340.679455] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [340.679654] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [340.679852] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [340.680098] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [340.680341] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [340.680539] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [340.680737] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [340.680950] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [340.681200] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [340.681436] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [340.681648] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [340.681859] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [340.682125] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [340.682339] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [340.682565] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [340.682762] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [340.682960] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [340.683258] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [340.683472] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [340.683671] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:387:pipe B] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [340.683885] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:387:pipe B] fastset requirement not met, forcing full modeset
<7> [340.684154] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [340.684374] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:387:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [340.684586] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:381:cursor B] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [340.684821] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:387:pipe B] min cdclk: 74250 kHz -> 0 kHz
<7> [340.685060] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [340.685437] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [340.685681] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:387:pipe B] enable: no [modeset]
<7> [340.685909] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:271:plane 1B] fb: [NOFB], visible: no
<7> [340.686213] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:301:plane 2B] fb: [NOFB], visible: no
<7> [340.686415] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:331:plane 3B] fb: [NOFB], visible: no
<7> [340.686628] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:361:plane 4B] fb: [NOFB], visible: no
<7> [340.686824] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:371:plane 5B] fb: [NOFB], visible: no
<7> [340.687094] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:381:cursor B] fb: [NOFB], visible: no
<7> [340.687456] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [340.698195] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [340.698473] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [340.698673] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [340.698869] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [340.699121] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [340.699318] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [340.699514] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [340.699710] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [340.699905] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [340.700137] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [340.700335] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [340.700530] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [340.700725] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [340.700921] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [340.701157] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [340.701353] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [340.701549] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [340.701764] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [340.701964] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [340.702196] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [340.702392] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [340.702588] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [340.702825] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [340.703174] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_B
<7> [340.703438] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [340.703684] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:387:pipe B]
<7> [340.704183] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [340.704354] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [340.704384] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:556:HDMI-A-3]
<7> [340.704458] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [340.704671] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [340.704855] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [340.705101] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:505:pipe D]
<7> [340.705290] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [340.705494] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [340.705697] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [340.705899] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [340.706138] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [340.706341] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [340.706543] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [340.706746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [340.706948] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [340.707190] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [340.707392] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [340.707594] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [340.707796] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [340.708036] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [340.708270] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [340.708472] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [340.708674] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [340.708876] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [340.709186] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [340.709388] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [340.709591] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [340.709793] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [340.710034] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [340.710236] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [340.710438] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [340.710641] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [340.710843] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [340.711049] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [340.711251] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [340.711453] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [340.711655] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [340.711856] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [340.712097] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [340.712300] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [340.712502] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [340.712705] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [340.712906] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [340.713142] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [340.713371] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [340.713563] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [340.713781] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [340.713996] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [340.714200] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [340.714391] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [340.714582] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0
<7> [340.714772] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0
<7> [340.715013] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [340.715213] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [340.715413] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [340.715629] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 74250 kHz
<7> [340.715845] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [340.716081] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [340.716282] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [340.716483] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [340.716684] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [340.716885] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [340.717121] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [340.717320] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [340.717521] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [340.717722] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [340.717922] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [340.718159] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [340.718359] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [340.718560] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [340.718761] 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> [340.718984] 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> [340.719200] 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> [340.719401] 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> [340.719603] 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> [340.719804] 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> [340.720049] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [340.720250] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [340.720450] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [340.720651] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [340.720851] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [340.721089] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [340.721291] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [340.721491] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [340.721692] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [340.721893] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [340.722130] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [340.722197] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [340.722331] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.722443] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [340.722532] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.722650] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [340.722732] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.722932] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [340.723299] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [340.723500] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.723699] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.723901] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [340.724137] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [340.724339] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [FB:562] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [340.724541] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [340.724742] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [340.724947] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [340.725180] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [340.725381] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [340.725582] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [340.725784] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [340.726442] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [340.726770] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [340.727201] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [340.727426] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_D
<7> [340.727679] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [340.727874] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [340.728111] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [340.728307] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [340.728502] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [340.728698] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [340.728892] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [340.729121] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [340.729316] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [340.729511] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [340.729707] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [340.729903] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [340.730137] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [340.730332] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [340.730527] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [340.730722] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [340.730918] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [340.731151] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [340.731347] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [340.731544] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [340.731740] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xd
<7> [340.732180] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [340.732510] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [340.751589] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [340.751920] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [351.486381] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:151:pipe A]
<7> [351.488088] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:269:pipe C]
<7> [351.489053] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:387:pipe B]
<7> [351.489843] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [351.490225] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [351.491324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [351.492153] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [351.492438] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [351.492725] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [351.492962] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [351.493160] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [351.493357] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [351.493669] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [351.493884] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [351.494083] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [351.494298] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [351.494558] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [351.494796] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [351.495022] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [351.495221] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [351.495503] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [351.495774] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [351.495986] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [351.496198] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [351.496415] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [351.496627] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [351.496838] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [351.497050] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [351.497262] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [351.497479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [351.497690] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [351.497901] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [351.498114] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [351.498332] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [351.498547] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [351.498758] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [351.498970] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [351.499182] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [351.499400] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [351.499613] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [351.499826] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [351.500039] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [351.500279] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [351.500481] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x0
<7> [351.500709] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [351.500910] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [351.501110] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [351.501318] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [351.501518] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [351.501719] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [351.501937] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 0 num active planes 0
<7> [351.502149] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<7> [351.502364] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [351.502592] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 74250 kHz -> 0 kHz
<7> [351.502822] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [351.503033] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [351.503248] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [351.503459] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: no [modeset]
<7> [351.503670] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [351.503881] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [351.504092] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [351.504310] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [351.504520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [351.504730] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [351.505060] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [351.519968] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [351.520255] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [351.520466] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [351.520671] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [351.520887] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [351.521094] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [351.521301] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [351.521506] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [351.521710] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [351.521922] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [351.522127] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [351.522332] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [351.522537] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [351.522749] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [351.522954] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [351.523158] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [351.523362] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [351.523588] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [351.523798] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [351.524004] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [351.524210] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [351.524414] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [351.524662] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [351.524993] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_D
<7> [351.525268] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_2
<7> [351.525525] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [351.525990] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CRTC:505:pipe D]
<7> [351.526024] i915 0000:03:00.0: [drm:drm_mode_setcrtc] [CONNECTOR:556:HDMI-A-3]
<7> [351.526108] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [351.526327] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [351.526519] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [351.526742] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:505:pipe D]
<7> [351.526942] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected no, found yes)
<7> [351.527154] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected no, found yes)
<7> [351.527366] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected -1, found 3)
<7> [351.527578] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 0, found 4)
<7> [351.527797] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [351.528008] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [351.528221] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [351.528432] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [351.528652] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [351.528865] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [351.529077] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [351.529289] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [351.529498] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [351.529756] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [351.529969] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [351.530182] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [351.530393] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [351.530611] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [351.530823] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [351.531035] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [351.531247] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [351.531458] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [351.531675] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [351.531887] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [351.532099] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [351.532311] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [351.532528] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [351.532740] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [351.532953] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [351.533164] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [351.533376] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [351.533594] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [351.533805] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [351.534017] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [351.534228] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [351.534440] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [351.534658] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [351.534870] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [351.535111] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [351.535313] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x8
<7> [351.535548] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [351.535749] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [351.535950] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [351.536150] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [351.536350] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0
<7> [351.536559] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0
<7> [351.536776] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 594000 num active planes 1
<7> [351.536988] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [351.537197] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [351.537432] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 0 kHz -> 74250 kHz
<7> [351.537658] i915 0000:03:00.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [i915]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [351.537868] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New cdclk calculated to be logical 163200 kHz, actual 163200 kHz
<7> [351.538077] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] New voltage level calculated to be logical 0, actual 0
<7> [351.538288] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: yes [modeset]
<7> [351.538506] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [351.538717] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: D, pipe bpp: 24, dithering: 0
<7> [351.538928] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [351.539138] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [351.539347] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [351.539564] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [351.539774] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [351.539985] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [351.540196] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [351.540412] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [351.540623] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [351.540833] 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> [351.541053] 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> [351.541255] 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> [351.541526] 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> [351.541730] 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> [351.541931] 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> [351.542132] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [351.542339] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [351.542540] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [351.542740] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [351.542941] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [351.543142] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [351.543383] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [351.543442] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [351.543583] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [351.543695] i915 0000:03:00.0: [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7> [351.543784] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [351.543902] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 01
<7> [351.543985] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [351.544186] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [351.544336] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [351.544394] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.544594] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.544795] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.544995] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [351.545195] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [351.545442] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.545642] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.545841] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [351.546042] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [351.546247] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [FB:562] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000009, visible: yes
<7> [351.546450] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [351.546651] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [351.546853] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [351.547053] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [351.547259] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [351.547460] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [351.547660] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [351.547937] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [351.548141] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [351.548512] i915 0000:03:00.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 01 to 00
<7> [351.548900] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_2
<7> [351.549124] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_D
<7> [351.549419] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [351.549616] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [351.549813] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [351.550008] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [351.550242] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [351.550438] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [351.550633] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [351.550828] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [351.551024] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [351.551260] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [351.551457] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [351.551652] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [351.551847] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [351.552042] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [351.552245] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [351.552442] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [351.552638] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [351.552833] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [351.553028] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [351.553228] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [351.553425] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xd
<7> [351.553818] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [351.554186] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe D
<7> [351.573237] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [351.573574] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<6> [359.445399] [IGT] kms_flip: finished subtest D-HDMI-A3, FAIL
<6> [359.447116] [IGT] kms_flip: finished subtest wf_vblank-ts-check-interruptible, FAIL
<7> [359.456536] i915 0000:03:00.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:563] from all active usage due to RMFB ioctl
<7> [359.457859] i915 0000:03:00.0: [drm:atomic_remove_fb] Disabling [PLANE:389:plane 1D] because [FB:563] is removed
<7> [359.458035] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:389:plane 1D] ddb ( 0 - 1963) -> ( 0 - 0), size 1963 -> 0
<7> [359.458796] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [359.459532] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] lines 4, 4, 4, 5, 9, 11, 17, 25, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [359.460291] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] blocks 62, 62, 62, 78, 139, 169, 261, 383, 137, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [359.461026] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:389:plane 1D] min_ddb 123, 123, 123, 184, 245, 245, 367, 489, 138, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [359.461824] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:505:pipe D] data rate 0 num active planes 0
<7> [359.462778] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 0
<7> [359.463539] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<6> [359.476900] [IGT] kms_flip: exiting, ret=98
<7> [359.509633] i915 0000:03:00.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:556:HDMI-A-3] Limiting target display pipe bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [359.509999] i915 0000:03:00.0: [drm:intel_hdmi_compute_output_format [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [359.510292] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [359.510683] i915 0000:03:00.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:547:DDI D/PHY D] [CRTC:151:pipe A]
<7> [359.510984] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [359.511306] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [359.511637] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0)
<7> [359.511959] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in lane_count (expected 0, found 4)
<7> [359.512280] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [359.512606] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [359.512925] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [359.513245] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [359.513578] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [359.513898] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [359.514202] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [359.514479] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [359.514764] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [359.515045] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [359.515324] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [359.515717] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [359.515997] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [359.516276] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [359.516687] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [359.516967] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [359.517244] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [359.518400] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [359.518696] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [359.518976] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [359.519245] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [359.519492] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [359.519748] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [359.519995] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [359.520243] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [359.520491] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [359.520746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [359.520994] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [359.521242] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [359.521496] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [359.521746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [359.521995] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [359.522243] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:151:pipe A] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [359.522498] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:151:pipe A] fastset requirement not met, forcing full modeset
<7> [359.522746] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.enable (expected yes, found no)
<7> [359.522994] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.active (expected yes, found no)
<7> [359.523241] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in cpu_transcoder (expected 3, found -1)
<7> [359.523496] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in lane_count (expected 4, found 0)
<7> [359.523745] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [359.523993] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [359.524231] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [359.524455] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [359.524689] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [359.524913] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [359.525136] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [359.525359] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [359.525588] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [359.525811] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [359.526035] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [359.526258] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [359.526487] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [359.526710] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [359.526933] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [359.527157] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [359.527380] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [359.527614] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [359.527838] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [359.528061] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [359.528284] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [359.528513] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [359.528738] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [359.528961] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [359.529180] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [359.529383] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [359.529696] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [359.529900] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [359.530103] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [359.530306] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [359.530589] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [359.530794] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [359.530997] i915 0000:03:00.0: [drm:intel_pipe_config_compare [i915]] [CRTC:505:pipe D] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [359.531200] i915 0000:03:00.0: [drm:intel_atomic_check [i915]] [CRTC:505:pipe D] fastset requirement not met, forcing full modeset
<7> [359.531465] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0xd -> 0x3 (total dbuf slices 0xf), mbus joined? no->no
<7> [359.531676] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:151:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x8 -> 0x1
<7> [359.531869] i915 0000:03:00.0: [drm:skl_compute_wm [i915]] [CRTC:505:pipe D] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x8 -> 0x1
<7> [359.532065] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:35:plane 1A] ddb ( 0 - 0) -> ( 0 - 1963), size 0 -> 1963
<7> [359.532256] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:145:cursor A] ddb ( 0 - 0) -> (1963 - 2048), size 0 -> 85
<7> [359.532486] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5,*wm6,*wm7,*twm, swm, stwm
<7> [359.532678] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 3, 4, 5, 9, 11, 17, 25, 0, 0, 0
<7> [359.532870] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 49, 65, 81, 145, 177, 273, 401, 30, 0, 0
<7> [359.533061] i915 0000:03:00.0: [drm:skl_print_plane_wm_changes [i915]] [PLANE:35:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 55, 73, 91, 161, 196, 302, 443, 31, 0, 0
<7> [359.533255] i915 0000:03:00.0: [drm:skl_print_plane_ddb_changes [i915]] [PLANE:499:cursor D] ddb (1963 - 2048) -> ( 0 - 0), size 85 -> 0
<7> [359.533502] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] [CRTC:151:pipe A] data rate 594000 num active planes 1
<7> [359.533705] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 50000 required 624
<7> [359.533906] i915 0000:03:00.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [359.534123] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:151:pipe A] min cdclk: 0 kHz -> 74250 kHz
<7> [359.534325] i915 0000:03:00.0: [drm:intel_cdclk_atomic_check [i915]] [CRTC:505:pipe D] min cdclk: 74250 kHz -> 0 kHz
<7> [359.534593] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:151:pipe A] enable: yes [modeset]
<7> [359.534797] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [359.535000] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [359.535201] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [359.535428] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [359.535643] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [359.535845] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [359.536046] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [359.536247] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] scanline offset: 2
<7> [359.536485] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0, set context latency: 0
<7> [359.536686] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [359.536888] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [359.537089] 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> [359.537291] 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> [359.537504] 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> [359.537707] 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> [359.537910] 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> [359.538112] 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> [359.538313] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] port clock: 148500, pixel rate 148500, min cdclk 74250, min voltage level 0
<7> [359.538520] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] linetime: 119, ips linetime: 0
<7> [359.538721] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0, sharpness_strength: 0
<7> [359.538922] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe src: 1920x1080+0+0
<7> [359.539124] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [359.539325] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] sharpness strength: 0, sharpness tap size: 0, sharpness enable: 0
<7> [359.539530] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [359.539731] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x24414, dpll_md: 0x18000000, fp0: 0x8801ef8, fp1: 0x2b000440
<7> [359.539933] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [359.540135] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [359.540336] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [359.540575] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.540775] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.540976] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.541178] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [359.541416] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [359.541617] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.541819] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.542019] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [359.542220] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [359.542462] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:plane 1A] fb: [FB:559] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [359.542665] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [359.542866] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [359.543068] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:65:plane 2A] fb: [NOFB], visible: no
<7> [359.543270] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:95:plane 3A] fb: [NOFB], visible: no
<7> [359.543510] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:125:plane 4A] fb: [NOFB], visible: no
<7> [359.543711] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:135:plane 5A] fb: [NOFB], visible: no
<7> [359.543912] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:145:cursor A] fb: [NOFB], visible: no
<7> [359.544113] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [CRTC:505:pipe D] enable: no [modeset]
<7> [359.544315] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:389:plane 1D] fb: [NOFB], visible: no
<7> [359.544554] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:419:plane 2D] fb: [NOFB], visible: no
<7> [359.544755] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:449:plane 3D] fb: [NOFB], visible: no
<7> [359.544957] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:479:plane 4D] fb: [NOFB], visible: no
<7> [359.545158] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:489:plane 5D] fb: [NOFB], visible: no
<7> [359.545396] i915 0000:03:00.0: [drm:intel_crtc_state_dump [i915]] [PLANE:499:cursor D] fb: [NOFB], visible: no
<7> [359.545711] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling PW_A
<7> [359.546124] i915 0000:03:00.0: [drm:intel_disable_transcoder [i915]] disabling pipe D
<7> [359.557395] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_D
<7> [359.557670] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:507:DDI A/PHY A]
<7> [359.557869] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:509:DP-MST A]
<7> [359.558066] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:510:DP-MST B]
<7> [359.558314] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:511:DP-MST C]
<7> [359.558512] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:512:DP-MST D]
<7> [359.558709] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:520:DDI B/PHY B]
<7> [359.558905] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:522:DP-MST A]
<7> [359.559101] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:523:DP-MST B]
<7> [359.559305] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:524:DP-MST C]
<7> [359.559503] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:525:DP-MST D]
<7> [359.559699] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:535:DDI C/PHY C]
<7> [359.559895] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:537:DP-MST A]
<7> [359.560092] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:538:DP-MST B]
<7> [359.560327] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:539:DP-MST C]
<7> [359.560523] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:540:DP-MST D]
<7> [359.560720] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:547:DDI D/PHY D]
<7> [359.560915] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:549:DP-MST A]
<7> [359.561111] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:550:DP-MST B]
<7> [359.561344] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:551:DP-MST C]
<7> [359.561540] i915 0000:03:00.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:552:DP-MST D]
<7> [359.561742] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0xf
<7> [359.562158] i915 0000:03:00.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_D
<7> [359.562530] i915 0000:03:00.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [359.564859] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [359.565063] i915 0000:03:00.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:35:plane 1A]
<7> [359.581248] i915 0000:03:00.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [359.581877] i915 0000:03:00.0: [drm:verify_connector_state [i915]] [CONNECTOR:556:HDMI-A-3]
<7> [359.582201] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:151:pipe A]
<7> [359.583182] i915 0000:03:00.0: [drm:intel_power_well_disable [i915]] disabling PW_D
<7> [359.583461] i915 0000:03:00.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:505:pipe D]
<7> [359.584027] i915 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0
Created at 2026-04-26 13:51:07