Results for igt@kms_flip@wf_vblank-ts-check-interruptible@c-hdmi-a1

Result: Fail

i915_display_info9 igt_runner9 results9.json results9-xe-load.json i915_display_info_post_exec9 boot9 dmesg9

DetailValue
Duration 18.62 seconds
Hostname
shard-adlp-6
Igt-Version
IGT-Version: 2.2-gb3d809d53 (x86_64) (Linux: 6.18.0-rc1-lgci-xe-xe-3941-b5e5976a35cb0e8e4+ x86_64)
Out
Starting dynamic subtest: C-HDMI-A1
  1920x1080: 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5 
Expected frametime: 16667us; measured 16675.1us +- 4.864us accuracy 0.09%
Event vblank: expected 450, counted 440, passrate = 2.27%, encoder type 2
Expected frametime: 16667us; measured 16838.3us +- 7.134us accuracy 0.13%
vblank interval differs from modeline! expected 16666.7us, measured 16838us +- 7.134us, difference 171.6us (24.1 sigma)
Event vblank: expected 448, counted 450, passrate = 71.11%, encoder type 2
Stack trace:
  #0 ../lib/igt_core.c:2075 __igt_fail_assert()
  #1 ../tests/kms_flip.c:973 run_test_on_crtc_set.constprop.0()
  #2 ../tests/kms_flip.c:1884 run_test()
  #3 ../tests/kms_flip.c:2153 __igt_unique____real_main2040()
  #4 ../tests/kms_flip.c:2040 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest C-HDMI-A1: FAIL (18.617s)
Err
Starting dynamic subtest: C-HDMI-A1
(kms_flip:6379) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1704:
(kms_flip:6379) CRITICAL: Failed assertion: !retried
(kms_flip:6379) CRITICAL: Last errno: 4, Interrupted system call
Dynamic subtest C-HDMI-A1 failed.
**** DEBUG ****
(kms_flip:6379) INFO: Expected frametime: 16667us; measured 16675.1us +- 4.864us accuracy 0.09%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 320.044495
current_received_ts = 320.044250
current_seq = 483
count = 0
seq_step = 10
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.044495
last_received_ts = 320.044250
last_seq = 483
current_ts = 320.215881
current_received_ts = 320.215576
current_seq = 493
count = 1
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.044495/483, current 320.215881/493: elapsed=171390.0us expected=166750.6us +- 833.8us, error 2.8%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.044495/483, current 320.215881/493: elapsed=171390.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.215881
last_received_ts = 320.215576
last_seq = 493
current_ts = 320.388824
current_received_ts = 320.388428
current_seq = 503
count = 2
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.215881/493, current 320.388824/503: elapsed=172947.0us expected=166750.6us +- 833.8us, error 3.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.215881/493, current 320.388824/503: elapsed=172947.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.388824
last_received_ts = 320.388428
last_seq = 503
current_ts = 320.561798
current_received_ts = 320.561432
current_seq = 513
count = 3
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.388824/503, current 320.561798/513: elapsed=172961.0us expected=166750.6us +- 833.8us, error 3.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.388824/503, current 320.561798/513: elapsed=172961.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.561798
last_received_ts = 320.561432
last_seq = 513
current_ts = 320.734741
current_received_ts = 320.734375
current_seq = 523
count = 4
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.561798/513, current 320.734741/523: elapsed=172960.0us expected=166750.6us +- 833.8us, error 3.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.561798/513, current 320.734741/523: elapsed=172960.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.734741
last_received_ts = 320.734375
last_seq = 523
current_ts = 320.907715
current_received_ts = 320.907288
current_seq = 533
count = 5
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.734741/523, current 320.907715/533: elapsed=172958.0us expected=166750.6us +- 833.8us, error 3.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.734741/523, current 320.907715/533: elapsed=172958.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 320.907715
last_received_ts = 320.907288
last_seq = 533
current_ts = 321.080658
current_received_ts = 321.080383
current_seq = 543
count = 6
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 320.907715/533, current 321.080658/543: elapsed=172952.0us expected=166750.6us +- 833.8us, error 3.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 320.907715/533, current 321.080658/543: elapsed=172952.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.080658
last_received_ts = 321.080383
last_seq = 543
current_ts = 321.252869
current_received_ts = 321.252533
current_seq = 553
count = 7
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.080658/543, current 321.252869/553: elapsed=172206.0us expected=166750.6us +- 833.8us, error 3.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.080658/543, current 321.252869/553: elapsed=172206.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.252869
last_received_ts = 321.252533
last_seq = 553
current_ts = 321.425049
current_received_ts = 321.424805
current_seq = 563
count = 8
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.252869/553, current 321.425049/563: elapsed=172180.0us expected=166750.6us +- 833.8us, error 3.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.252869/553, current 321.425049/563: elapsed=172180.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.425049
last_received_ts = 321.424805
last_seq = 563
current_ts = 321.597229
current_received_ts = 321.596771
current_seq = 573
count = 9
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.425049/563, current 321.597229/573: elapsed=172181.0us expected=166750.6us +- 833.8us, error 3.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.425049/563, current 321.597229/573: elapsed=172181.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.597229
last_received_ts = 321.596771
last_seq = 573
current_ts = 321.769409
current_received_ts = 321.769226
current_seq = 583
count = 10
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.597229/573, current 321.769409/583: elapsed=172184.0us expected=166750.6us +- 833.8us, error 3.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.597229/573, current 321.769409/583: elapsed=172184.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.769409
last_received_ts = 321.769226
last_seq = 583
current_ts = 321.941620
current_received_ts = 321.941315
current_seq = 593
count = 11
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.769409/583, current 321.941620/593: elapsed=172195.0us expected=166750.6us +- 833.8us, error 3.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.769409/583, current 321.941620/593: elapsed=172195.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 321.941620
last_received_ts = 321.941315
last_seq = 593
current_ts = 322.113678
current_received_ts = 322.113464
current_seq = 603
count = 12
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 321.941620/593, current 322.113678/603: elapsed=172073.0us expected=166750.6us +- 833.8us, error 3.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 321.941620/593, current 322.113678/603: elapsed=172073.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.113678
last_received_ts = 322.113464
last_seq = 603
current_ts = 322.285187
current_received_ts = 322.284790
current_seq = 613
count = 13
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.113678/603, current 322.285187/613: elapsed=171497.0us expected=166750.6us +- 833.8us, error 2.8%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.113678/603, current 322.285187/613: elapsed=171497.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.285187
last_received_ts = 322.284790
last_seq = 613
current_ts = 322.456696
current_received_ts = 322.456299
current_seq = 623
count = 14
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.285187/613, current 322.456696/623: elapsed=171502.0us expected=166750.6us +- 833.8us, error 2.8%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.285187/613, current 322.456696/623: elapsed=171502.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.456696
last_received_ts = 322.456299
last_seq = 623
current_ts = 322.628174
current_received_ts = 322.627838
current_seq = 633
count = 15
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.456696/623, current 322.628174/633: elapsed=171503.0us expected=166750.6us +- 833.8us, error 2.8%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.456696/623, current 322.628174/633: elapsed=171503.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.628174
last_received_ts = 322.627838
last_seq = 633
current_ts = 322.799683
current_received_ts = 322.799316
current_seq = 643
count = 16
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.628174/633, current 322.799683/643: elapsed=171492.0us expected=166750.6us +- 833.8us, error 2.8%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.628174/633, current 322.799683/643: elapsed=171492.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.799683
last_received_ts = 322.799316
last_seq = 643
current_ts = 322.971191
current_received_ts = 322.970917
current_seq = 653
count = 17
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.799683/643, current 322.971191/653: elapsed=171517.0us expected=166750.6us +- 833.8us, error 2.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.799683/643, current 322.971191/653: elapsed=171517.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 322.971191
last_received_ts = 322.970917
last_seq = 653
current_ts = 323.142487
current_received_ts = 323.142059
current_seq = 663
count = 18
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 322.971191/653, current 323.142487/663: elapsed=171302.0us expected=166750.6us +- 833.8us, error 2.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 322.971191/653, current 323.142487/663: elapsed=171302.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.142487
last_received_ts = 323.142059
last_seq = 663
current_ts = 323.313416
current_received_ts = 323.312988
current_seq = 673
count = 19
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.142487/663, current 323.313416/673: elapsed=170908.0us expected=166750.6us +- 833.8us, error 2.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.142487/663, current 323.313416/673: elapsed=170908.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.313416
last_received_ts = 323.312988
last_seq = 673
current_ts = 323.484314
current_received_ts = 323.483978
current_seq = 683
count = 20
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.313416/673, current 323.484314/683: elapsed=170906.0us expected=166750.6us +- 833.8us, error 2.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.313416/673, current 323.484314/683: elapsed=170906.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.484314
last_received_ts = 323.483978
last_seq = 683
current_ts = 323.655212
current_received_ts = 323.654755
current_seq = 693
count = 21
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.484314/683, current 323.655212/693: elapsed=170911.0us expected=166750.6us +- 833.8us, error 2.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.484314/683, current 323.655212/693: elapsed=170911.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.655212
last_received_ts = 323.654755
last_seq = 693
current_ts = 323.826111
current_received_ts = 323.825836
current_seq = 703
count = 22
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.655212/693, current 323.826111/703: elapsed=170904.0us expected=166750.6us +- 833.8us, error 2.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.655212/693, current 323.826111/703: elapsed=170904.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.826111
last_received_ts = 323.825836
last_seq = 703
current_ts = 323.997040
current_received_ts = 323.996704
current_seq = 713
count = 23
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.826111/703, current 323.997040/713: elapsed=170914.0us expected=166750.6us +- 833.8us, error 2.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.826111/703, current 323.997040/713: elapsed=170914.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 323.997040
last_received_ts = 323.996704
last_seq = 713
current_ts = 324.167694
current_received_ts = 324.167358
current_seq = 723
count = 24
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 323.997040/713, current 324.167694/723: elapsed=170662.0us expected=166750.6us +- 833.8us, error 2.3%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 323.997040/713, current 324.167694/723: elapsed=170662.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 324.167694
last_received_ts = 324.167358
last_seq = 723
current_ts = 324.338074
current_received_ts = 324.337860
current_seq = 733
count = 25
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 324.167694/723, current 324.338074/733: elapsed=170383.0us expected=166750.6us +- 833.8us, error 2.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 324.167694/723, current 324.338074/733: elapsed=170383.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 324.338074
last_received_ts = 324.337860
last_seq = 733
current_ts = 324.508484
current_received_ts = 324.508301
current_seq = 743
count = 26
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 324.338074/733, current 324.508484/743: elapsed=170391.0us expected=166750.6us +- 833.8us, error 2.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 324.338074/733, current 324.508484/743: elapsed=170391.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 324.508484
last_received_ts = 324.508301
last_seq = 743
current_ts = 324.678864
current_received_ts = 324.678497
current_seq = 753
count = 27
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 324.508484/743, current 324.678864/753: elapsed=170384.0us expected=166750.6us +- 833.8us, error 2.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 324.508484/743, current 324.678864/753: elapsed=170384.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 324.678864
last_received_ts = 324.678497
last_seq = 753
current_ts = 324.849243
current_received_ts = 324.849091
current_seq = 763
count = 28
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 324.678864/753, current 324.849243/763: elapsed=170396.0us expected=166750.6us +- 833.8us, error 2.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 324.678864/753, current 324.849243/763: elapsed=170396.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 324.849243
last_received_ts = 324.849091
last_seq = 763
current_ts = 325.019623
current_received_ts = 325.019287
current_seq = 773
count = 29
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 324.849243/763, current 325.019623/773: elapsed=170378.0us expected=166750.6us +- 833.8us, error 2.2%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 324.849243/763, current 325.019623/773: elapsed=170378.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.019623
last_received_ts = 325.019287
last_seq = 773
current_ts = 325.189758
current_received_ts = 325.189362
current_seq = 783
count = 30
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.019623/773, current 325.189758/783: elapsed=170115.0us expected=166750.6us +- 833.8us, error 2.0%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.019623/773, current 325.189758/783: elapsed=170115.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.189758
last_received_ts = 325.189362
last_seq = 783
current_ts = 325.359680
current_received_ts = 325.359314
current_seq = 793
count = 31
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.189758/783, current 325.359680/793: elapsed=169930.0us expected=166750.6us +- 833.8us, error 1.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.189758/783, current 325.359680/793: elapsed=169930.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.359680
last_received_ts = 325.359314
last_seq = 793
current_ts = 325.529633
current_received_ts = 325.529327
current_seq = 803
count = 32
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.359680/793, current 325.529633/803: elapsed=169947.0us expected=166750.6us +- 833.8us, error 1.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.359680/793, current 325.529633/803: elapsed=169947.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.529633
last_received_ts = 325.529327
last_seq = 803
current_ts = 325.699554
current_received_ts = 325.699158
current_seq = 813
count = 33
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.529633/803, current 325.699554/813: elapsed=169927.0us expected=166750.6us +- 833.8us, error 1.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.529633/803, current 325.699554/813: elapsed=169927.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.699554
last_received_ts = 325.699158
last_seq = 813
current_ts = 325.869507
current_received_ts = 325.869263
current_seq = 823
count = 34
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.699554/813, current 325.869507/823: elapsed=169942.0us expected=166750.6us +- 833.8us, error 1.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.699554/813, current 325.869507/823: elapsed=169942.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 325.869507
last_received_ts = 325.869263
last_seq = 823
current_ts = 326.039429
current_received_ts = 326.039246
current_seq = 833
count = 35
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 325.869507/823, current 326.039429/833: elapsed=169927.0us expected=166750.6us +- 833.8us, error 1.9%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 325.869507/823, current 326.039429/833: elapsed=169927.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.039429
last_received_ts = 326.039246
last_seq = 833
current_ts = 326.209076
current_received_ts = 326.208740
current_seq = 843
count = 36
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.039429/833, current 326.209076/843: elapsed=169648.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.039429/833, current 326.209076/843: elapsed=169648.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.209076
last_received_ts = 326.208740
last_seq = 843
current_ts = 326.378601
current_received_ts = 326.378174
current_seq = 853
count = 37
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.209076/843, current 326.378601/853: elapsed=169534.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.209076/843, current 326.378601/853: elapsed=169534.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.378601
last_received_ts = 326.378174
last_seq = 853
current_ts = 326.548126
current_received_ts = 326.547729
current_seq = 863
count = 38
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.378601/853, current 326.548126/863: elapsed=169532.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.378601/853, current 326.548126/863: elapsed=169532.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.548126
last_received_ts = 326.547729
last_seq = 863
current_ts = 326.717682
current_received_ts = 326.717316
current_seq = 873
count = 39
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.548126/863, current 326.717682/873: elapsed=169545.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.548126/863, current 326.717682/873: elapsed=169545.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.717682
last_received_ts = 326.717316
last_seq = 873
current_ts = 326.887207
current_received_ts = 326.886810
current_seq = 883
count = 40
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.717682/873, current 326.887207/883: elapsed=169521.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.717682/873, current 326.887207/883: elapsed=169521.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 326.887207
last_received_ts = 326.886810
last_seq = 883
current_ts = 327.056763
current_received_ts = 327.056366
current_seq = 893
count = 41
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 326.887207/883, current 327.056763/893: elapsed=169550.0us expected=166750.6us +- 833.8us, error 1.7%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 326.887207/883, current 327.056763/893: elapsed=169550.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 327.056763
last_received_ts = 327.056366
last_seq = 893
current_ts = 327.225983
current_received_ts = 327.225616
current_seq = 903
count = 42
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 327.056763/893, current 327.225983/903: elapsed=169247.0us expected=166750.6us +- 833.8us, error 1.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 327.056763/893, current 327.225983/903: elapsed=169247.0us expected=166750.6us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 327.225983
last_received_ts = 327.225616
last_seq = 903
current_ts = 327.395172
current_received_ts = 327.394714
current_seq = 913
count = 43
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 327.225983/903, current 327.395172/913: elapsed=169177.0us expected=166750.6us +- 833.8us, error 1.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 327.225983/903, current 327.395172/913: elapsed=169177.0us expected=166750.6us
(kms_flip:6379) INFO: Event vblank: expected 450, counted 440, passrate = 2.27%, encoder type 2
(kms_flip:6379) DEBUG: dropped frames, expected 450, counted 440, passrate = 2.27%, encoder type 2
(kms_flip:6379) DEBUG: Retrying without a hotplug event
(kms_flip:6379) DEBUG: No stale events found
(kms_flip:6379) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0
(kms_flip:6379) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0
(kms_flip:6379) INFO: Expected frametime: 16667us; measured 16838.3us +- 7.134us accuracy 0.13%
(kms_flip:6379) INFO: vblank interval differs from modeline! expected 16666.7us, measured 16838us +- 7.134us, difference 171.6us (24.1 sigma)
(kms_flip:6379) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 330.923553
current_received_ts = 330.923279
current_seq = 944
count = 0
seq_step = 10
(kms_flip:6379) DEBUG: name = vblank
last_ts = 330.923553
last_received_ts = 330.923279
last_seq = 944
current_ts = 331.091919
current_received_ts = 331.091644
current_seq = 954
count = 1
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 330.923553/944, current 331.091919/954: elapsed=168367.0us expected=168383.1us +- 841.9us, error 0.0%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.091919
last_received_ts = 331.091644
last_seq = 954
current_ts = 331.260071
current_received_ts = 331.259796
current_seq = 964
count = 2
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.091919/954, current 331.260071/964: elapsed=168175.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.260071
last_received_ts = 331.259796
last_seq = 964
current_ts = 331.428253
current_received_ts = 331.427765
current_seq = 974
count = 3
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.260071/964, current 331.428253/974: elapsed=168185.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.428253
last_received_ts = 331.427765
last_seq = 974
current_ts = 331.596436
current_received_ts = 331.596039
current_seq = 984
count = 4
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.428253/974, current 331.596436/984: elapsed=168182.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.596436
last_received_ts = 331.596039
last_seq = 984
current_ts = 331.764618
current_received_ts = 331.764313
current_seq = 994
count = 5
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.596436/984, current 331.764618/994: elapsed=168177.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.764618
last_received_ts = 331.764313
last_seq = 994
current_ts = 331.932800
current_received_ts = 331.932312
current_seq = 1004
count = 6
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.764618/994, current 331.932800/1004: elapsed=168169.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 331.932800
last_received_ts = 331.932312
last_seq = 1004
current_ts = 332.100952
current_received_ts = 332.100647
current_seq = 1014
count = 7
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 331.932800/1004, current 332.100952/1014: elapsed=168166.0us expected=168383.1us +- 841.9us, error 0.1%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.100952
last_received_ts = 332.100647
last_seq = 1014
current_ts = 332.268951
current_received_ts = 332.268646
current_seq = 1024
count = 8
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.100952/1014, current 332.268951/1024: elapsed=168001.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.268951
last_received_ts = 332.268646
last_seq = 1024
current_ts = 332.436981
current_received_ts = 332.436676
current_seq = 1034
count = 9
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.268951/1024, current 332.436981/1034: elapsed=168007.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.436981
last_received_ts = 332.436676
last_seq = 1034
current_ts = 332.604980
current_received_ts = 332.604645
current_seq = 1044
count = 10
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.436981/1034, current 332.604980/1044: elapsed=168004.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.604980
last_received_ts = 332.604645
last_seq = 1044
current_ts = 332.772980
current_received_ts = 332.772675
current_seq = 1054
count = 11
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.604980/1044, current 332.772980/1054: elapsed=168001.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.772980
last_received_ts = 332.772675
last_seq = 1054
current_ts = 332.940979
current_received_ts = 332.940674
current_seq = 1064
count = 12
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.772980/1054, current 332.940979/1064: elapsed=167999.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 332.940979
last_received_ts = 332.940674
last_seq = 1064
current_ts = 333.108948
current_received_ts = 333.108612
current_seq = 1074
count = 13
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 332.940979/1064, current 333.108948/1074: elapsed=167979.0us expected=168383.1us +- 841.9us, error 0.2%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.108948
last_received_ts = 333.108612
last_seq = 1074
current_ts = 333.276794
current_received_ts = 333.276428
current_seq = 1084
count = 14
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.108948/1074, current 333.276794/1084: elapsed=167838.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.276794
last_received_ts = 333.276428
last_seq = 1084
current_ts = 333.444641
current_received_ts = 333.444336
current_seq = 1094
count = 15
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.276794/1084, current 333.444641/1094: elapsed=167846.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.444641
last_received_ts = 333.444336
last_seq = 1094
current_ts = 333.612488
current_received_ts = 333.612061
current_seq = 1104
count = 16
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.444641/1094, current 333.612488/1104: elapsed=167838.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.612488
last_received_ts = 333.612061
last_seq = 1104
current_ts = 333.780304
current_received_ts = 333.780060
current_seq = 1114
count = 17
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.612488/1104, current 333.780304/1114: elapsed=167841.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.780304
last_received_ts = 333.780060
last_seq = 1114
current_ts = 333.948181
current_received_ts = 333.947906
current_seq = 1124
count = 18
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.780304/1114, current 333.948181/1124: elapsed=167854.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 333.948181
last_received_ts = 333.947906
last_seq = 1124
current_ts = 334.115997
current_received_ts = 334.115723
current_seq = 1134
count = 19
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 333.948181/1124, current 334.115997/1134: elapsed=167817.0us expected=168383.1us +- 841.9us, error 0.3%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.115997
last_received_ts = 334.115723
last_seq = 1134
current_ts = 334.283691
current_received_ts = 334.283234
current_seq = 1144
count = 20
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.115997/1134, current 334.283691/1144: elapsed=167702.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.283691
last_received_ts = 334.283234
last_seq = 1144
current_ts = 334.451416
current_received_ts = 334.451233
current_seq = 1154
count = 21
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.283691/1144, current 334.451416/1154: elapsed=167715.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.451416
last_received_ts = 334.451233
last_seq = 1154
current_ts = 334.619110
current_received_ts = 334.618896
current_seq = 1164
count = 22
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.451416/1154, current 334.619110/1164: elapsed=167703.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.619110
last_received_ts = 334.618896
last_seq = 1164
current_ts = 334.786835
current_received_ts = 334.786438
current_seq = 1174
count = 23
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.619110/1164, current 334.786835/1174: elapsed=167716.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.786835
last_received_ts = 334.786438
last_seq = 1174
current_ts = 334.954529
current_received_ts = 334.954315
current_seq = 1184
count = 24
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.786835/1174, current 334.954529/1184: elapsed=167701.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 334.954529
last_received_ts = 334.954315
last_seq = 1184
current_ts = 335.122223
current_received_ts = 335.121948
current_seq = 1194
count = 25
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 334.954529/1184, current 335.122223/1194: elapsed=167687.0us expected=168383.1us +- 841.9us, error 0.4%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.122223
last_received_ts = 335.121948
last_seq = 1194
current_ts = 335.289795
current_received_ts = 335.289398
current_seq = 1204
count = 26
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.122223/1194, current 335.289795/1204: elapsed=167578.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.289795
last_received_ts = 335.289398
last_seq = 1204
current_ts = 335.457367
current_received_ts = 335.456909
current_seq = 1214
count = 27
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.289795/1204, current 335.457367/1214: elapsed=167593.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.457367
last_received_ts = 335.456909
last_seq = 1214
current_ts = 335.624969
current_received_ts = 335.624695
current_seq = 1224
count = 28
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.457367/1214, current 335.624969/1224: elapsed=167588.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.624969
last_received_ts = 335.624695
last_seq = 1224
current_ts = 335.792542
current_received_ts = 335.792297
current_seq = 1234
count = 29
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.624969/1224, current 335.792542/1234: elapsed=167586.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.792542
last_received_ts = 335.792297
last_seq = 1234
current_ts = 335.960144
current_received_ts = 335.959900
current_seq = 1244
count = 30
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.792542/1234, current 335.960144/1244: elapsed=167588.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 335.960144
last_received_ts = 335.959900
last_seq = 1244
current_ts = 336.127686
current_received_ts = 336.127472
current_seq = 1254
count = 31
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 335.960144/1244, current 336.127686/1254: elapsed=167555.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.127686
last_received_ts = 336.127472
last_seq = 1254
current_ts = 336.295197
current_received_ts = 336.294983
current_seq = 1264
count = 32
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.127686/1254, current 336.295197/1264: elapsed=167489.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.127686/1254, current 336.295197/1264: elapsed=167489.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.295197
last_received_ts = 336.294983
last_seq = 1264
current_ts = 336.462677
current_received_ts = 336.462341
current_seq = 1274
count = 33
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.295197/1264, current 336.462677/1274: elapsed=167480.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.295197/1264, current 336.462677/1274: elapsed=167480.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.462677
last_received_ts = 336.462341
last_seq = 1274
current_ts = 336.630157
current_received_ts = 336.629761
current_seq = 1284
count = 34
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.462677/1274, current 336.630157/1284: elapsed=167489.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.462677/1274, current 336.630157/1284: elapsed=167489.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.630157
last_received_ts = 336.629761
last_seq = 1284
current_ts = 336.797638
current_received_ts = 336.797211
current_seq = 1294
count = 35
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.630157/1284, current 336.797638/1294: elapsed=167482.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.630157/1284, current 336.797638/1294: elapsed=167482.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.797638
last_received_ts = 336.797211
last_seq = 1294
current_ts = 336.965118
current_received_ts = 336.964844
current_seq = 1304
count = 36
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.797638/1294, current 336.965118/1304: elapsed=167474.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.797638/1294, current 336.965118/1304: elapsed=167474.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 336.965118
last_received_ts = 336.964844
last_seq = 1304
current_ts = 337.132568
current_received_ts = 337.132294
current_seq = 1314
count = 37
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 336.965118/1304, current 337.132568/1314: elapsed=167462.0us expected=168383.1us +- 841.9us, error 0.5%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 336.965118/1304, current 337.132568/1314: elapsed=167462.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.132568
last_received_ts = 337.132294
last_seq = 1314
current_ts = 337.299957
current_received_ts = 337.299652
current_seq = 1324
count = 38
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.132568/1314, current 337.299957/1324: elapsed=167388.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.132568/1314, current 337.299957/1324: elapsed=167388.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.299957
last_received_ts = 337.299652
last_seq = 1324
current_ts = 337.467346
current_received_ts = 337.466888
current_seq = 1334
count = 39
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.299957/1324, current 337.467346/1334: elapsed=167395.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.299957/1324, current 337.467346/1334: elapsed=167395.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.467346
last_received_ts = 337.466888
last_seq = 1334
current_ts = 337.634735
current_received_ts = 337.634369
current_seq = 1344
count = 40
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.467346/1334, current 337.634735/1344: elapsed=167390.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.467346/1334, current 337.634735/1344: elapsed=167390.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.634735
last_received_ts = 337.634369
last_seq = 1344
current_ts = 337.802155
current_received_ts = 337.801849
current_seq = 1354
count = 41
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.634735/1344, current 337.802155/1354: elapsed=167394.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.634735/1344, current 337.802155/1354: elapsed=167394.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.802155
last_received_ts = 337.801849
last_seq = 1354
current_ts = 337.969513
current_received_ts = 337.969299
current_seq = 1364
count = 42
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.802155/1354, current 337.969513/1364: elapsed=167387.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.802155/1354, current 337.969513/1364: elapsed=167387.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 337.969513
last_received_ts = 337.969299
last_seq = 1364
current_ts = 338.136902
current_received_ts = 338.136353
current_seq = 1374
count = 43
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 337.969513/1364, current 338.136902/1374: elapsed=167375.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 337.969513/1364, current 338.136902/1374: elapsed=167375.0us expected=168383.1us
(kms_flip:6379) DEBUG: name = vblank
last_ts = 338.136902
last_received_ts = 338.136353
last_seq = 1374
current_ts = 338.304199
current_received_ts = 338.304108
current_seq = 1384
count = 44
seq_step = 10
(kms_flip:6379) DEBUG: vblank ts/seq: last 338.136902/1374, current 338.304199/1384: elapsed=167303.0us expected=168383.1us +- 841.9us, error 0.6%
(kms_flip:6379) DEBUG: inconsistent vblank ts/seq: last 338.136902/1374, current 338.304199/1384: elapsed=167303.0us expected=168383.1us
(kms_flip:6379) INFO: Event vblank: expected 448, counted 450, passrate = 71.11%, encoder type 2
(kms_flip:6379) DEBUG: dropped frames, expected 448, counted 450, passrate = 71.11%, encoder type 2
(kms_flip:6379) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1704:
(kms_flip:6379) CRITICAL: Failed assertion: !retried
(kms_flip:6379) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:6379) igt_core-INFO: Stack trace:
(kms_flip:6379) igt_core-INFO:   #0 ../lib/igt_core.c:2075 __igt_fail_assert()
(kms_flip:6379) igt_core-INFO:   #1 ../tests/kms_flip.c:973 run_test_on_crtc_set.constprop.0()
(kms_flip:6379) igt_core-INFO:   #2 ../tests/kms_flip.c:1884 run_test()
(kms_flip:6379) igt_core-INFO:   #3 ../tests/kms_flip.c:2153 __igt_unique____real_main2040()
(kms_flip:6379) igt_core-INFO:   #4 ../tests/kms_flip.c:2040 main()
(kms_flip:6379) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(kms_flip:6379) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(kms_flip:6379) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest C-HDMI-A1: FAIL (18.617s)
Dmesg
<6> [333.883065] [IGT] kms_flip: starting dynamic subtest C-HDMI-A1
<7> [333.884108] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:318]
<7> [333.884481] xe 0000:00:02.0: [drm:drm_mode_addfb2] [FB:320]
<7> [333.892032] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DC_off
<7> [333.892194] xe 0000:00:02.0: [drm:skl_enable_dc6 [xe]] Enabling DC6
<7> [333.892356] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 00 to 02
<7> [333.924002] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [333.924103] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DC_off
<7> [333.924216] xe 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [xe]] Setting DC state from 02 to 00
<7> [333.924390] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [333.924462] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.enable (expected yes, found no)
<7> [333.924569] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.active (expected yes, found no)
<7> [333.924668] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [333.924764] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in lane_count (expected 4, found 0)
<7> [333.924858] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [333.924982] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [333.925148] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [333.925289] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [333.925419] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [333.925549] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [333.925685] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [333.925808] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [333.925910] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [333.926020] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [333.926126] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [333.926242] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [333.926332] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [333.926427] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [333.926520] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [333.926629] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [333.926761] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [333.926901] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [333.927075] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [333.927222] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [333.927338] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [333.927449] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [333.927545] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [333.927643] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [333.927735] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [333.927826] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [333.927956] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [333.928063] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [333.928162] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [333.928257] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [333.928354] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [333.928450] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [333.928547] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:145:pipe B] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [333.928645] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:145:pipe B] fastset requirement not met, forcing full modeset
<7> [333.928746] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:145:pipe B] releasing DPLL 0
<7> [333.929049] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xf -> 0x1 (total dbuf slices 0xf), mbus joined? yes->no
<7> [333.929154] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:145:pipe B] dbuf slices 0xf -> 0x0, ddb (0 - 4096) -> (0 - 0), active pipes 0x2 -> 0x0
<7> [333.929259] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:140:cursor B] ddb (4060 - 4096) -> ( 0 - 0), size 36 -> 0
<7> [333.929349] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:145:pipe B] min cdclk: 74250 kHz -> 0 kHz
<7> [333.929452] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:145:pipe B] enable: no [modeset]
<7> [333.929550] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:90:plane 1B] fb: [NOFB], visible: no
<7> [333.929646] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:100:plane 2B] fb: [NOFB], visible: no
<7> [333.929741] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:110:plane 3B] fb: [NOFB], visible: no
<7> [333.929834] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:120:plane 4B] fb: [NOFB], visible: no
<7> [333.929960] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:130:plane 5B] fb: [NOFB], visible: no
<7> [333.930071] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:140:cursor B] fb: [NOFB], visible: no
<7> [333.930257] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe B
<7> [333.950103] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DDI_IO_B
<7> [333.950282] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:145:pipe B]
<7> [333.950442] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [333.950594] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI B/PHY B]
<7> [333.950737] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [333.950873] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [333.951045] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [333.951190] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [333.951322] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC1/PHY TC1]
<7> [333.951449] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [333.951576] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [333.951699] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [333.951822] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [333.951964] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:289:DDI TC2/PHY TC2]
<7> [333.952078] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:291:DP-MST A]
<7> [333.952193] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DP-MST B]
<7> [333.952306] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:293:DP-MST C]
<7> [333.952421] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:294:DP-MST D]
<7> [333.952535] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DDI TC3/PHY TC3]
<7> [333.952654] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST A]
<7> [333.952764] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST B]
<7> [333.952868] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:302:DP-MST C]
<7> [333.952981] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:303:DP-MST D]
<7> [333.953084] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:307:DDI TC4/PHY TC4]
<7> [333.953186] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:309:DP-MST A]
<7> [333.953285] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:310:DP-MST B]
<7> [333.953381] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:311:DP-MST C]
<7> [333.953478] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:312:DP-MST D]
<7> [333.953576] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:275:HDMI-A-1]
<7> [333.953703] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [333.953817] xe 0000:00:02.0: [drm:intel_dbuf_mbus_join_update [xe]] Changing mbus joined: yes -> no (pipe: *)
<7> [333.953908] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [333.954067] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_B
<7> [333.954181] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [333.954300] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:145:pipe B]
<7> [333.954504] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [333.954620] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:259:pipe D]
<7> [333.954717] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [333.954759] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:275:HDMI-A-1]
<7> [333.954830] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:275:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [333.954980] xe 0000:00:02.0: [drm:intel_hdmi_compute_clock [xe]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [333.955100] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [333.955208] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:261:DDI B/PHY B] [CRTC:202:pipe C]
<7> [333.955304] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [333.955401] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [333.955499] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [333.955594] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [333.955686] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [333.955777] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [333.955867] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [333.955978] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [333.956088] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [333.956185] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [333.956278] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [333.956374] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [333.956471] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [333.956566] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [333.956669] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [333.956772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [333.956871] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [333.956984] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [333.957092] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [333.957192] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [333.957281] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [333.957371] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [333.957469] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [333.957572] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [333.957673] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [333.957772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [333.957876] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [333.958005] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [333.958163] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [333.958288] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [333.958404] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [333.958516] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [333.958631] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [333.958748] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [333.958861] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [333.958981] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [333.959089] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [333.959193] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] fastset requirement not met, forcing full modeset
<7> [333.959321] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [333.959414] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:202:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [333.959531] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:147:plane 1C] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [333.959624] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:197:cursor C] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [333.959714] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [333.959800] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 4, 6, 7, 10, 10, 0, 0, 0, 3, 0
<7> [333.959893] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 65, 97, 113, 161, 161, 0, 0, 30, 49, 63
<7> [333.959991] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 73, 108, 126, 179, 179, 0, 0, 31, 55, 64
<7> [333.960095] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:202:pipe C] data rate 594000 num active planes 1
<7> [333.960210] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 10441 required 624
<7> [333.960322] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 17848 required 624
<7> [333.960436] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 18818 required 624
<7> [333.960547] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 18818 required 624
<7> [333.960654] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 0: max bw 34133 required 624
<7> [333.960761] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 1: max bw 51200 required 624
<7> [333.960866] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 2: max bw 51200 required 624
<7> [333.960998] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:202:pipe C] min cdclk: 0 kHz -> 74250 kHz
<7> [333.961125] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:202:pipe C] allocated DPLL 0
<7> [333.961236] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:202:pipe C] reserving DPLL 0
<7> [333.961346] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:202:pipe C] enable: yes [modeset]
<7> [333.961465] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [333.961577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [333.961687] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [333.961794] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [333.961921] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [333.962031] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [333.962140] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [333.962248] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 2
<7> [333.962360] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 set context latency: 0
<7> [333.962468] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [333.962577] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [333.962683] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [333.962790] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [333.962903] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] 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> [333.963010] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [333.963116] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] 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> [333.963220] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [333.963325] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [333.963435] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [333.963543] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [333.963650] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [333.963755] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [333.963863] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [333.963985] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [333.964093] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [333.964201] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.964307] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.964412] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.964517] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [333.964620] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [333.964724] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.964829] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.964939] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [333.965042] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [333.965138] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:147:plane 1C] fb: [FB:318] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [333.965237] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [333.965341] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [333.965441] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:157:plane 2C] fb: [NOFB], visible: no
<7> [333.965540] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:167:plane 3C] fb: [NOFB], visible: no
<7> [333.965637] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:177:plane 4C] fb: [NOFB], visible: no
<7> [333.965732] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:187:plane 5C] fb: [NOFB], visible: no
<7> [333.965827] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:cursor C] fb: [NOFB], visible: no
<7> [333.966186] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [333.966333] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [333.966481] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI B/PHY B]
<7> [333.966587] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [333.966687] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [333.966783] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [333.966877] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [333.966981] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC1/PHY TC1]
<7> [333.967075] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [333.967165] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [333.967256] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [333.967349] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [333.967439] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:289:DDI TC2/PHY TC2]
<7> [333.967527] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:291:DP-MST A]
<7> [333.967615] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DP-MST B]
<7> [333.967699] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:293:DP-MST C]
<7> [333.967783] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:294:DP-MST D]
<7> [333.967873] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DDI TC3/PHY TC3]
<7> [333.967988] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST A]
<7> [333.968086] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST B]
<7> [333.968185] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:302:DP-MST C]
<7> [333.968283] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:303:DP-MST D]
<7> [333.968379] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:307:DDI TC4/PHY TC4]
<7> [333.968472] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:309:DP-MST A]
<7> [333.968564] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:310:DP-MST B]
<7> [333.968661] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:311:DP-MST C]
<7> [333.968756] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:312:DP-MST D]
<7> [333.968857] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [333.969022] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [333.969115] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:202:pipe C]
<7> [333.969220] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [333.969356] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DDI_IO_B
<7> [333.969505] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [333.987102] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:275:HDMI-A-1]
<7> [333.987204] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:202:pipe C]
<7> [344.586897] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:88:pipe A]
<7> [344.587508] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:145:pipe B]
<7> [344.587918] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [344.588254] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.enable (expected yes, found no)
<7> [344.588952] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.active (expected yes, found no)
<7> [344.589537] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in cpu_transcoder (expected 2, found -1)
<7> [344.590073] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in lane_count (expected 4, found 0)
<7> [344.590621] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000)
<7> [344.591132] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [344.591671] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [344.592201] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2200, found 0)
<7> [344.592757] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [344.593269] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2200, found 0)
<7> [344.593772] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0)
<7> [344.593880] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2052, found 0)
<7> [344.593984] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0)
<7> [344.594090] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0)
<7> [344.594194] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0)
<7> [344.594299] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0)
<7> [344.594424] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0)
<7> [344.594541] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0)
<7> [344.594647] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [344.594752] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2200, found 0)
<7> [344.594856] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [344.594959] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2200, found 0)
<7> [344.595065] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0)
<7> [344.595168] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2052, found 0)
<7> [344.595271] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0)
<7> [344.595378] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0)
<7> [344.595492] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0)
<7> [344.595601] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0)
<7> [344.595702] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0)
<7> [344.595804] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0)
<7> [344.595901] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [344.596000] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [344.596097] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0)
<7> [344.596197] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [344.596298] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 148500, found 0)
<7> [344.596409] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 148500, found 0)
<7> [344.596523] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in port_clock (expected 148500, found 0)
<7> [344.596629] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] fastset requirement not met, forcing full modeset
<7> [344.596725] xe 0000:00:02.0: [drm:intel_dpll_crtc_put [xe]] [CRTC:202:pipe C] releasing DPLL 0
<7> [344.596854] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0xd -> 0x1 (total dbuf slices 0xf), mbus joined? no->no
<7> [344.596979] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:202:pipe C] dbuf slices 0xc -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x4 -> 0x0
<7> [344.597091] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:147:plane 1C] ddb ( 0 - 2012) -> ( 0 - 0), size 2012 -> 0
<7> [344.597182] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:197:cursor C] ddb (2012 - 2048) -> ( 0 - 0), size 36 -> 0
<7> [344.597272] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] level *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm
<7> [344.597366] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] lines 1, 4, 6, 7, 10, 10, 0, 0, 0, 3, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [344.597461] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] blocks 16, 65, 97, 113, 161, 161, 0, 0, 30, 49, 63 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [344.597547] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] min_ddb 19, 73, 108, 126, 179, 179, 0, 0, 31, 55, 64 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0
<7> [344.597641] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:202:pipe C] data rate 0 num active planes 0
<7> [344.597728] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 10441 required 0
<7> [344.597809] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 17848 required 0
<7> [344.597888] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 18818 required 0
<7> [344.597968] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 18818 required 0
<7> [344.598049] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 0: max bw 34133 required 0
<7> [344.598129] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 1: max bw 51200 required 0
<7> [344.598208] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 2: max bw 51200 required 0
<7> [344.598304] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:202:pipe C] min cdclk: 74250 kHz -> 0 kHz
<7> [344.598427] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 9282 kHz -> 0 kHz
<7> [344.598537] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:202:pipe C] enable: no [modeset]
<7> [344.598641] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:147:plane 1C] fb: [NOFB], visible: no
<7> [344.598735] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:157:plane 2C] fb: [NOFB], visible: no
<7> [344.598825] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:167:plane 3C] fb: [NOFB], visible: no
<7> [344.598915] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:177:plane 4C] fb: [NOFB], visible: no
<7> [344.599005] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:187:plane 5C] fb: [NOFB], visible: no
<7> [344.599095] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:cursor C] fb: [NOFB], visible: no
<7> [344.599495] xe 0000:00:02.0: [drm:intel_disable_transcoder [xe]] disabling pipe C
<7> [344.604381] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling DDI_IO_B
<7> [344.604493] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disable DPLL 0 (active 0x4, on? 1) for [CRTC:202:pipe C]
<7> [344.604580] xe 0000:00:02.0: [drm:intel_dpll_disable [xe]] disabling DPLL 0
<7> [344.604666] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI B/PHY B]
<7> [344.604744] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [344.604816] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [344.604886] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [344.604956] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [344.605029] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC1/PHY TC1]
<7> [344.605101] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [344.605171] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [344.605239] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [344.605323] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [344.605416] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:289:DDI TC2/PHY TC2]
<7> [344.605513] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:291:DP-MST A]
<7> [344.605596] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DP-MST B]
<7> [344.605678] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:293:DP-MST C]
<7> [344.605757] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:294:DP-MST D]
<7> [344.605836] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DDI TC3/PHY TC3]
<7> [344.605914] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST A]
<7> [344.605994] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST B]
<7> [344.606078] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:302:DP-MST C]
<7> [344.606158] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:303:DP-MST D]
<7> [344.606239] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:307:DDI TC4/PHY TC4]
<7> [344.606341] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:309:DP-MST A]
<7> [344.606435] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:310:DP-MST B]
<7> [344.606525] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:311:DP-MST C]
<7> [344.606603] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:312:DP-MST D]
<7> [344.606681] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:275:HDMI-A-1]
<7> [344.606776] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [344.606852] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0x1
<7> [344.606995] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_C
<7> [344.607091] xe 0000:00:02.0: [drm:intel_power_well_disable [xe]] disabling PW_2
<7> [344.607195] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:202:pipe C]
<7> [344.607557] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:259:pipe D]
<7> [344.607695] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:202:pipe C]
<7> [344.607728] xe 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:275:HDMI-A-1]
<7> [344.607788] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CONNECTOR:275:HDMI-A-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 36, max platform bpp 36)
<7> [344.607898] xe 0000:00:02.0: [drm:intel_hdmi_compute_clock [xe]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [344.607993] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [344.608090] xe 0000:00:02.0: [drm:intel_ddi_compute_config_late [xe]] [ENCODER:261:DDI B/PHY B] [CRTC:202:pipe C]
<7> [344.608189] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.enable (expected no, found yes)
<7> [344.608302] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.active (expected no, found yes)
<7> [344.608413] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in cpu_transcoder (expected -1, found 2)
<7> [344.608512] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in lane_count (expected 0, found 4)
<7> [344.608606] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040)
<7> [344.608702] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [344.608797] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [344.608892] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2200)
<7> [344.608991] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [344.609086] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2200)
<7> [344.609177] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008)
<7> [344.609280] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2052)
<7> [344.609382] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080)
<7> [344.609475] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080)
<7> [344.609563] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084)
<7> [344.609663] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089)
<7> [344.609760] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125)
<7> [344.609853] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125)
<7> [344.609944] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [344.610035] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2200)
<7> [344.610124] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<7> [344.610218] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2200)
<7> [344.610322] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008)
<7> [344.610429] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2052)
<7> [344.610517] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080)
<7> [344.610606] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080)
<7> [344.610694] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084)
<7> [344.610783] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089)
<7> [344.610872] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125)
<7> [344.610962] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125)
<7> [344.611051] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [344.611140] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [344.611240] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4)
<7> [344.611347] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [344.611453] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 148500)
<7> [344.611546] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 148500)
<7> [344.611637] xe 0000:00:02.0: [drm:intel_pipe_config_compare [xe]] [CRTC:202:pipe C] fastset requirement not met in port_clock (expected 0, found 148500)
<7> [344.611733] xe 0000:00:02.0: [drm:intel_atomic_check [xe]] [CRTC:202:pipe C] fastset requirement not met, forcing full modeset
<7> [344.611845] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] Enabled dbuf slices 0x1 -> 0xd (total dbuf slices 0xf), mbus joined? no->no
<7> [344.611923] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [CRTC:202:pipe C] dbuf slices 0x0 -> 0xc, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x4
<7> [344.612016] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:147:plane 1C] ddb ( 0 - 0) -> ( 0 - 2012), size 0 -> 2012
<7> [344.612094] xe 0000:00:02.0: [drm:skl_compute_wm [xe]] [PLANE:197:cursor C] ddb ( 0 - 0) -> (2012 - 2048), size 0 -> 36
<7> [344.612170] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> *wm0,*wm1,*wm2,*wm3,*wm4,*wm5, wm6, wm7,*twm,*swm,*stwm
<7> [344.612263] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 4, 6, 7, 10, 10, 0, 0, 0, 3, 0
<7> [344.612350] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 65, 97, 113, 161, 161, 0, 0, 30, 49, 63
<7> [344.612434] xe 0000:00:02.0: [drm:skl_print_plane_changes [xe]] [PLANE:147:plane 1C] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 73, 108, 126, 179, 179, 0, 0, 31, 55, 64
<7> [344.612520] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] [CRTC:202:pipe C] data rate 594000 num active planes 1
<7> [344.612618] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 0: max bw 10441 required 624
<7> [344.612714] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 1: max bw 17848 required 624
<7> [344.612806] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 2: max bw 18818 required 624
<7> [344.612897] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] QGV point 3: max bw 18818 required 624
<7> [344.612985] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 0: max bw 34133 required 624
<7> [344.613074] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 1: max bw 51200 required 624
<7> [344.613164] xe 0000:00:02.0: [drm:intel_bw_atomic_check [xe]] PSF GV point 2: max bw 51200 required 624
<7> [344.613287] xe 0000:00:02.0: [drm:intel_cdclk_atomic_check [xe]] [CRTC:202:pipe C] min cdclk: 0 kHz -> 74250 kHz
<7> [344.613397] xe 0000:00:02.0: [drm:intel_cdclk_update_dbuf_bw_min_cdclk [xe]] dbuf bandwidth min cdclk: 0 kHz -> 9282 kHz
<7> [344.613491] xe 0000:00:02.0: [drm:intel_find_dpll [xe]] [CRTC:202:pipe C] allocated DPLL 0
<7> [344.613586] xe 0000:00:02.0: [drm:intel_dpll_crtc_get [xe]] [CRTC:202:pipe C] reserving DPLL 0
<7> [344.613678] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [CRTC:202:pipe C] enable: yes [modeset]
<7> [344.613781] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [344.613876] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] cpu_transcoder: C, pipe bpp: 24, dithering: 0
<7> [344.613967] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] MST master transcoder: <invalid>
<7> [344.614060] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [344.614154] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] joiner: no, pipes: 0x0
<7> [344.614269] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] splitter: disabled, link count 0, overlap 0
<7> [344.614373] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [344.614470] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] scanline offset: 2
<7> [344.614557] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vblank delay: 0, framestart delay: 1, MSA timing delay: 0 set context latency: 0
<7> [344.614644] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: no, fixed rr: yes, vmin: 1125, vmax: 1125, flipline: 1125, pipeline full: 0, guardband: 45 vsync start: 41, vsync end: 36
<7> [344.614730] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] vrr: vmin vblank: 1080, vmax vblank: 1080, vmin vtotal: 1125, vmax vtotal: 1125
<7> [344.614819] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] requested mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [344.614909] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] adjusted mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x48 0x5
<7> [344.614997] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] 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> [344.615086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe mode: "1920x1080": 60 148500 1920 2008 2052 2200 1080 1084 1089 1125 0x40 0x5
<7> [344.615182] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] 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> [344.615286] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] port clock: 148500, pipe src: 1920x1080+0+0, pixel rate 148500, min cdclk 74250
<7> [344.615387] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] linetime: 119, ips linetime: 0
<7> [344.615484] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [344.615578] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [344.615670] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] ips: 0, double wide: 0, drrs: 0
<7> [344.615762] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] dpll_hw_state: cfgcr0: 0x1001d0, cfgcr1: 0xe84, div0: 0x0, mg_refclkin_ctl: 0x0, hg_clktop2_coreclkctl1: 0x0, mg_clktop2_hsclkctl: 0x0, mg_pll_div0: 0x0, mg_pll_div2: 0x0, mg_pll_lf: 0x0, mg_pll_frac_lock: 0x0, mg_pll_ssc: 0x0, mg_pll_bias: 0x0, mg_pll_tdc_coldst_bias: 0x0
<7> [344.615861] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [344.615953] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [344.616043] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [344.616134] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616250] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616365] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616462] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [344.616553] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [344.616643] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616731] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616818] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [344.616906] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [344.616995] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:147:plane 1C] fb: [FB:318] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x100000000000001, visible: yes
<7> [344.617086] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [344.617185] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [344.617290] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:157:plane 2C] fb: [NOFB], visible: no
<7> [344.617383] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:167:plane 3C] fb: [NOFB], visible: no
<7> [344.617471] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:177:plane 4C] fb: [NOFB], visible: no
<7> [344.617559] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:187:plane 5C] fb: [NOFB], visible: no
<7> [344.617647] xe 0000:00:02.0: [drm:intel_crtc_state_dump [xe]] [PLANE:197:cursor C] fb: [NOFB], visible: no
<7> [344.617952] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_2
<7> [344.618080] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling PW_C
<7> [344.618222] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:261:DDI B/PHY B]
<7> [344.618316] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:263:DP-MST A]
<7> [344.618402] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:264:DP-MST B]
<7> [344.618486] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:265:DP-MST C]
<7> [344.618576] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:266:DP-MST D]
<7> [344.618664] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:280:DDI TC1/PHY TC1]
<7> [344.618748] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:282:DP-MST A]
<7> [344.618831] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:283:DP-MST B]
<7> [344.618911] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:284:DP-MST C]
<7> [344.618992] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:285:DP-MST D]
<7> [344.619076] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:289:DDI TC2/PHY TC2]
<7> [344.619174] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:291:DP-MST A]
<7> [344.619272] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:292:DP-MST B]
<7> [344.619370] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:293:DP-MST C]
<7> [344.619450] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:294:DP-MST D]
<7> [344.619530] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:298:DDI TC3/PHY TC3]
<7> [344.619613] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:300:DP-MST A]
<7> [344.619694] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:301:DP-MST B]
<7> [344.619773] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:302:DP-MST C]
<7> [344.619850] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:303:DP-MST D]
<7> [344.619927] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:307:DDI TC4/PHY TC4]
<7> [344.620016] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:309:DP-MST A]
<7> [344.620100] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:310:DP-MST B]
<7> [344.620249] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:311:DP-MST C]
<7> [344.620340] xe 0000:00:02.0: [drm:intel_modeset_verify_disabled [xe]] [ENCODER:312:DP-MST D]
<7> [344.620431] xe 0000:00:02.0: [drm:gen9_dbuf_slices_update [xe]] Updating dbuf slices to 0xd
<7> [344.620572] xe 0000:00:02.0: [drm:intel_dbuf_mdclk_cdclk_ratio_update [xe]] Updating dbuf ratio to 2 (mbus joined: no)
<7> [344.620659] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enable DPLL 0 (active 0x4, on? 0) for [CRTC:202:pipe C]
<7> [344.620752] xe 0000:00:02.0: [drm:intel_dpll_enable [xe]] enabling DPLL 0
<7> [344.620871] xe 0000:00:02.0: [drm:intel_power_well_enable [xe]] enabling DDI_IO_B
<7> [344.620999] xe 0000:00:02.0: [drm:intel_enable_transcoder [xe]] enabling pipe C
<7> [344.638612] xe 0000:00:02.0: [drm:verify_connector_state [xe]] [CONNECTOR:275:HDMI-A-1]
<7> [344.638719] xe 0000:00:02.0: [drm:intel_modeset_verify_crtc [xe]] [CRTC:202:pipe C]
<6> [352.500403] [IGT] kms_flip: finished subtest C-HDMI-A1, FAIL
Created at 2025-10-18 15:01:28