Result:
i915_display_info9 igt_runner9 results9.json results9-xe-load.json i915_display_info_post_exec9 boot9 dmesg9
| Detail | Value |
|---|---|
| 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
|