Hardware List

Results for igt@kms_flip@flip-vs-blocking-wf-vblank

Machine description: shard-rkl-3

Result: Fail

git-log-oneline i915_display_info12 igt_runner12 runtimes12 results12.json i915_display_info_post_exec12 boot12 dmesg12

Detail	Value
Duration	26.90 seconds
Hostname	shard-rkl-3
Igt-Version	IGT-Version: 1.30-g8bce68b09 (x86_64) (Linux: 6.13.0-rc3-CI_DRM_15862-g1d0ef0c9b38e+ x86_64)
Out	Using IGT_SRANDOM=1734545022 for randomisation Opened device: /dev/dri/card0 Using monotonic timestamps Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Expected frametime: 18974us; measured 19177.1us +- 7.070us accuracy 0.11% vblank interval differs from modeline! expected 18973.8us, measured 19177us +- 7.070us, difference 203.4us (28.8 sigma) Stack trace: #0 ../lib/igt_core.c:2051 __igt_fail_assert() #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1845 run_test() #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() #4 ../tests/kms_flip.c:2001 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest A-HDMI-A2: FAIL (8.262s) Starting dynamic subtest: B-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Expected frametime: 18974us; measured 19043.6us +- 5.018us accuracy 0.08% vblank interval differs from modeline! expected 18973.8us, measured 19044us +- 5.018us, difference 69.9us (13.9 sigma) Stack trace: #0 ../lib/igt_core.c:2051 __igt_fail_assert() #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() #2 ../tests/kms_flip.c:1845 run_test() #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() #4 ../tests/kms_flip.c:2001 main() #5 [__libc_init_first+0x8a] #6 [__libc_start_main+0x8b] #7 [_start+0x25] Dynamic subtest B-HDMI-A2: FAIL (18.633s) Subtest flip-vs-blocking-wf-vblank: FAIL (26.900s) Stack trace: #0 ../lib/igt_core.c:2051 __igt_fail_assert() #1 ../lib/igt_kms.c:2246 kmstest_set_connector_dpms() #2 ../lib/igt_core.c:2930 igt_atexit_handler() #3 [erand48_r+0x1d6] #4 [exit+0x1e] #5 ../lib/igt_core.c:2386 igt_exit() #6 [main+0x3f] #7 [__libc_init_first+0x8a] #8 [__libc_start_main+0x8b] #9 [_start+0x25]
Err	Starting subtest: flip-vs-blocking-wf-vblank Starting dynamic subtest: A-HDMI-A2 (kms_flip:2036) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2036) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest A-HDMI-A2 failed. ** DEBUG (kms_flip:2036) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:2036) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680) (kms_flip:2036) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2036) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:2036) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:2036) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:2036) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2036) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2036) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2036) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2036) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:2036) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:2036) igt_kms-INFO: 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 (kms_flip:2036) DEBUG: No stale events found (kms_flip:2036) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:2036) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:2036) INFO: Expected frametime: 18974us; measured 19177.1us +- 7.070us accuracy 0.11% (kms_flip:2036) INFO: vblank interval differs from modeline! expected 18973.8us, measured 19177us +- 7.070us, difference 203.4us (28.8 sigma) (kms_flip:2036) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 60.577877 current_received_ts = 60.749790 current_seq = 382 count = 0 seq_step = 1 (kms_flip:2036) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 60.750473 current_received_ts = 60.749763 current_seq = 391 count = 0 seq_step = 10 (kms_flip:2036) DEBUG: name = flip last_ts = 60.577877 last_received_ts = 60.749790 last_seq = 382 current_ts = 60.769665 current_received_ts = 60.941551 current_seq = 392 count = 1 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 60.577877/382, current 60.769665/392: elapsed=191786.0us expected=191771.2us +- 958.9us, error 0.0% (kms_flip:2036) DEBUG: name = vblank last_ts = 60.750473 last_received_ts = 60.749763 last_seq = 391 current_ts = 60.942242 current_received_ts = 60.941528 current_seq = 401 count = 1 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 60.750473/391, current 60.942242/401: elapsed=191769.0us expected=191771.2us +- 958.9us, error 0.0% (kms_flip:2036) DEBUG: name = flip last_ts = 60.769665 last_received_ts = 60.941551 last_seq = 392 current_ts = 60.961418 current_received_ts = 61.133266 current_seq = 402 count = 2 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 60.769665/392, current 60.961418/402: elapsed=191753.0us expected=191771.2us +- 958.9us, error 0.0% (kms_flip:2036) DEBUG: name = vblank last_ts = 60.942242 last_received_ts = 60.941528 last_seq = 401 current_ts = 61.133976 current_received_ts = 61.133244 current_seq = 411 count = 2 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 60.942242/401, current 61.133976/411: elapsed=191735.0us expected=191771.2us +- 958.9us, error 0.0% (kms_flip:2036) DEBUG: name = flip last_ts = 60.961418 last_received_ts = 61.133266 last_seq = 402 current_ts = 61.153114 current_received_ts = 61.324787 current_seq = 412 count = 3 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 60.961418/402, current 61.153114/412: elapsed=191697.0us expected=191771.2us +- 958.9us, error 0.0% (kms_flip:2036) DEBUG: name = vblank last_ts = 61.133976 last_received_ts = 61.133244 last_seq = 411 current_ts = 61.325478 current_received_ts = 61.324764 current_seq = 421 count = 3 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 61.133976/411, current 61.325478/421: elapsed=191500.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 61.153114 last_received_ts = 61.324787 last_seq = 412 current_ts = 61.344627 current_received_ts = 61.516300 current_seq = 422 count = 4 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 61.153114/412, current 61.344627/422: elapsed=191513.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = vblank last_ts = 61.325478 last_received_ts = 61.324764 last_seq = 421 current_ts = 61.517006 current_received_ts = 61.516277 current_seq = 431 count = 4 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 61.325478/421, current 61.517006/431: elapsed=191531.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 61.344627 last_received_ts = 61.516300 last_seq = 422 current_ts = 61.536140 current_received_ts = 61.707813 current_seq = 432 count = 5 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 61.344627/422, current 61.536140/432: elapsed=191515.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = vblank last_ts = 61.517006 last_received_ts = 61.516277 last_seq = 431 current_ts = 61.708523 current_received_ts = 61.707790 current_seq = 441 count = 5 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 61.517006/431, current 61.708523/441: elapsed=191515.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 61.536140 last_received_ts = 61.707813 last_seq = 432 current_ts = 61.727673 current_received_ts = 61.899342 current_seq = 442 count = 6 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 61.536140/432, current 61.727673/442: elapsed=191532.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = vblank last_ts = 61.708523 last_received_ts = 61.707790 last_seq = 441 current_ts = 61.900021 current_received_ts = 61.899315 current_seq = 451 count = 6 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 61.708523/441, current 61.900021/451: elapsed=191500.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 61.727673 last_received_ts = 61.899342 last_seq = 442 current_ts = 61.919189 current_received_ts = 62.090847 current_seq = 452 count = 7 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 61.727673/442, current 61.919189/452: elapsed=191515.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = vblank last_ts = 61.900021 last_received_ts = 61.899315 last_seq = 451 current_ts = 62.091537 current_received_ts = 62.090824 current_seq = 461 count = 7 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 61.900021/451, current 62.091537/461: elapsed=191517.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 61.919189 last_received_ts = 62.090847 last_seq = 452 current_ts = 62.110683 current_received_ts = 62.282158 current_seq = 462 count = 8 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 61.919189/452, current 62.110683/462: elapsed=191493.0us expected=191771.2us +- 958.9us, error 0.1% (kms_flip:2036) DEBUG: name = vblank last_ts = 62.091537 last_received_ts = 62.090824 last_seq = 461 current_ts = 62.282848 current_received_ts = 62.282127 current_seq = 471 count = 8 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 62.091537/461, current 62.282848/471: elapsed=191308.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 62.110683 last_received_ts = 62.282158 last_seq = 462 current_ts = 62.301968 current_received_ts = 62.473450 current_seq = 472 count = 9 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 62.110683/462, current 62.301968/472: elapsed=191287.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 62.282848 last_received_ts = 62.282127 last_seq = 471 current_ts = 62.474140 current_received_ts = 62.473415 current_seq = 481 count = 9 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 62.282848/471, current 62.474140/481: elapsed=191294.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 62.301968 last_received_ts = 62.473450 last_seq = 472 current_ts = 62.493271 current_received_ts = 62.664734 current_seq = 482 count = 10 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 62.301968/472, current 62.493271/482: elapsed=191300.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 62.474140 last_received_ts = 62.473415 last_seq = 481 current_ts = 62.665436 current_received_ts = 62.664707 current_seq = 491 count = 10 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 62.474140/481, current 62.665436/491: elapsed=191294.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 62.493271 last_received_ts = 62.664734 last_seq = 482 current_ts = 62.684563 current_received_ts = 62.856068 current_seq = 492 count = 11 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 62.493271/482, current 62.684563/492: elapsed=191294.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 62.665436 last_received_ts = 62.664707 last_seq = 491 current_ts = 62.856728 current_received_ts = 62.855999 current_seq = 501 count = 11 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 62.665436/491, current 62.856728/501: elapsed=191294.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 62.684563 last_received_ts = 62.856068 last_seq = 492 current_ts = 62.875843 current_received_ts = 63.047321 current_seq = 502 count = 12 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 62.684563/492, current 62.875843/502: elapsed=191280.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 62.856728 last_received_ts = 62.855999 last_seq = 501 current_ts = 63.048019 current_received_ts = 63.047295 current_seq = 511 count = 12 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 62.856728/501, current 63.048019/511: elapsed=191291.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 62.875843 last_received_ts = 63.047321 last_seq = 502 current_ts = 63.067150 current_received_ts = 63.238461 current_seq = 512 count = 13 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 62.875843/502, current 63.067150/512: elapsed=191307.0us expected=191771.2us +- 958.9us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 63.048019 last_received_ts = 63.047295 last_seq = 511 current_ts = 63.239147 current_received_ts = 63.238434 current_seq = 521 count = 13 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 63.048019/511, current 63.239147/521: elapsed=191126.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 63.067150 last_received_ts = 63.238461 last_seq = 512 current_ts = 63.258270 current_received_ts = 63.429558 current_seq = 522 count = 14 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 63.067150/512, current 63.258270/522: elapsed=191120.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 63.239147 last_received_ts = 63.238434 last_seq = 521 current_ts = 63.430244 current_received_ts = 63.429531 current_seq = 531 count = 14 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 63.239147/521, current 63.430244/531: elapsed=191099.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 63.258270 last_received_ts = 63.429558 last_seq = 522 current_ts = 63.449368 current_received_ts = 63.620655 current_seq = 532 count = 15 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 63.258270/522, current 63.449368/532: elapsed=191099.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 63.430244 last_received_ts = 63.429531 last_seq = 531 current_ts = 63.621361 current_received_ts = 63.620628 current_seq = 541 count = 15 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 63.430244/531, current 63.621361/541: elapsed=191115.0us expected=191771.2us +- 958.9us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 63.449368 last_received_ts = 63.620655 last_seq = 532 current_ts = 63.640469 current_received_ts = 63.811752 current_seq = 542 count = 16 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 63.449368/532, current 63.640469/542: elapsed=191100.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 63.621361 last_received_ts = 63.620628 last_seq = 541 current_ts = 63.812458 current_received_ts = 63.811729 current_seq = 551 count = 16 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 63.621361/541, current 63.812458/551: elapsed=191099.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 63.640469 last_received_ts = 63.811752 last_seq = 542 current_ts = 63.831566 current_received_ts = 64.002853 current_seq = 552 count = 17 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 63.640469/542, current 63.831566/552: elapsed=191098.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 63.812458 last_received_ts = 63.811729 last_seq = 551 current_ts = 64.003540 current_received_ts = 64.002831 current_seq = 561 count = 17 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 63.812458/551, current 64.003540/561: elapsed=191083.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 63.831566 last_received_ts = 64.002853 last_seq = 552 current_ts = 64.022667 current_received_ts = 64.193855 current_seq = 562 count = 18 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 63.831566/552, current 64.022667/562: elapsed=191100.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.003540 last_received_ts = 64.002831 last_seq = 561 current_ts = 64.194565 current_received_ts = 64.193832 current_seq = 571 count = 18 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.003540/561, current 64.194565/571: elapsed=191020.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 64.022667 last_received_ts = 64.193855 last_seq = 562 current_ts = 64.213654 current_received_ts = 64.384789 current_seq = 572 count = 19 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.022667/562, current 64.213654/572: elapsed=190989.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.194565 last_received_ts = 64.193832 last_seq = 571 current_ts = 64.385475 current_received_ts = 64.384766 current_seq = 581 count = 19 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.194565/571, current 64.385475/581: elapsed=190915.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 64.213654 last_received_ts = 64.384789 last_seq = 572 current_ts = 64.404587 current_received_ts = 64.575714 current_seq = 582 count = 20 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.213654/572, current 64.404587/582: elapsed=190928.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.385475 last_received_ts = 64.384766 last_seq = 581 current_ts = 64.576424 current_received_ts = 64.575691 current_seq = 591 count = 20 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.385475/581, current 64.576424/591: elapsed=190943.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 64.404587 last_received_ts = 64.575714 last_seq = 582 current_ts = 64.595512 current_received_ts = 64.766655 current_seq = 592 count = 21 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.404587/582, current 64.595512/592: elapsed=190929.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.576424 last_received_ts = 64.575691 last_seq = 591 current_ts = 64.767349 current_received_ts = 64.766624 current_seq = 601 count = 21 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.576424/591, current 64.767349/601: elapsed=190930.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 64.595512 last_received_ts = 64.766655 last_seq = 592 current_ts = 64.786446 current_received_ts = 64.957573 current_seq = 602 count = 22 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.595512/592, current 64.786446/602: elapsed=190929.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.767349 last_received_ts = 64.766624 last_seq = 601 current_ts = 64.958267 current_received_ts = 64.957550 current_seq = 611 count = 22 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.767349/601, current 64.958267/611: elapsed=190914.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = flip last_ts = 64.786446 last_received_ts = 64.957573 last_seq = 602 current_ts = 64.977371 current_received_ts = 65.148468 current_seq = 612 count = 23 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.786446/602, current 64.977371/612: elapsed=190929.0us expected=191771.2us +- 958.9us, error 0.4% (kms_flip:2036) DEBUG: name = vblank last_ts = 64.958267 last_received_ts = 64.957550 last_seq = 611 current_ts = 65.149147 current_received_ts = 65.148438 current_seq = 621 count = 23 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 64.958267/611, current 65.149147/621: elapsed=190884.0us expected=191771.2us +- 958.9us, error 0.5% (kms_flip:2036) DEBUG: name = flip last_ts = 64.977371 last_received_ts = 65.148468 last_seq = 612 current_ts = 65.168228 current_received_ts = 65.339249 current_seq = 622 count = 24 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 64.977371/612, current 65.168228/622: elapsed=190857.0us expected=191771.2us +- 958.9us, error 0.5% (kms_flip:2036) DEBUG: name = vblank last_ts = 65.149147 last_received_ts = 65.148438 last_seq = 621 current_ts = 65.339943 current_received_ts = 65.339218 current_seq = 631 count = 24 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 65.149147/621, current 65.339943/631: elapsed=190796.0us expected=191771.2us +- 958.9us, error 0.5% (kms_flip:2036) DEBUG: inconsistent vblank ts/seq: last 65.149147/621, current 65.339943/631: elapsed=190796.0us expected=191771.2us (kms_flip:2036) DEBUG: expected 122810, counted 240, encoder type 2 (kms_flip:2036) DEBUG: dropped frames, expected 122810, counted 240, encoder type 2 (kms_flip:2036) DEBUG: expected 122810, counted 240, encoder type 2 (kms_flip:2036) DEBUG: dropped frames, expected 122810, counted 240, encoder type 2 (kms_flip:2036) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2036) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:2036) igt_core-INFO: Stack trace: (kms_flip:2036) igt_core-INFO: #0 ../lib/igt_core.c:2051 __igt_fail_assert() (kms_flip:2036) igt_core-INFO: #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() (kms_flip:2036) igt_core-INFO: #2 ../tests/kms_flip.c:1845 run_test() (kms_flip:2036) igt_core-INFO: #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() (kms_flip:2036) igt_core-INFO: #4 ../tests/kms_flip.c:2001 main() (kms_flip:2036) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2036) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2036) igt_core-INFO: #7 [_start+0x25] END Dynamic subtest A-HDMI-A2: FAIL (8.262s) Starting dynamic subtest: B-HDMI-A2 (kms_flip:2036) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2036) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest B-HDMI-A2 failed. DEBUG (kms_flip:2036) DEBUG: name = flip last_ts = 72.285110 last_received_ts = 72.455544 last_seq = 201 current_ts = 72.475258 current_received_ts = 72.645691 current_seq = 211 count = 19 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 72.285110/201, current 72.475258/211: elapsed=190146.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 72.456245 last_received_ts = 72.455513 last_seq = 210 current_ts = 72.646378 current_received_ts = 72.645660 current_seq = 220 count = 19 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 72.456245/210, current 72.646378/220: elapsed=190131.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 72.475258 last_received_ts = 72.645691 last_seq = 211 current_ts = 72.665405 current_received_ts = 72.835831 current_seq = 221 count = 20 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 72.475258/211, current 72.665405/221: elapsed=190147.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 72.646378 last_received_ts = 72.645660 last_seq = 220 current_ts = 72.836533 current_received_ts = 72.835808 current_seq = 230 count = 20 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 72.646378/220, current 72.836533/230: elapsed=190161.0us expected=190436.3us +- 952.2us, error 0.1% (kms_flip:2036) DEBUG: name = flip last_ts = 72.665405 last_received_ts = 72.835831 last_seq = 221 current_ts = 72.855553 current_received_ts = 73.025978 current_seq = 231 count = 21 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 72.665405/221, current 72.855553/231: elapsed=190148.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 72.836533 last_received_ts = 72.835808 last_seq = 230 current_ts = 73.026688 current_received_ts = 73.025955 current_seq = 240 count = 21 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 72.836533/230, current 73.026688/240: elapsed=190149.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 72.855553 last_received_ts = 73.025978 last_seq = 231 current_ts = 73.045700 current_received_ts = 73.216095 current_seq = 241 count = 22 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 72.855553/231, current 73.045700/241: elapsed=190147.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.026688 last_received_ts = 73.025955 last_seq = 240 current_ts = 73.216805 current_received_ts = 73.216072 current_seq = 250 count = 22 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.026688/240, current 73.216805/250: elapsed=190116.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.045700 last_received_ts = 73.216095 last_seq = 241 current_ts = 73.235809 current_received_ts = 73.406197 current_seq = 251 count = 23 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.045700/241, current 73.235809/251: elapsed=190111.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.216805 last_received_ts = 73.216072 last_seq = 250 current_ts = 73.406883 current_received_ts = 73.406166 current_seq = 260 count = 23 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.216805/250, current 73.406883/260: elapsed=190080.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.235809 last_received_ts = 73.406197 last_seq = 251 current_ts = 73.425903 current_received_ts = 73.596268 current_seq = 261 count = 24 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.235809/251, current 73.425903/261: elapsed=190096.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.406883 last_received_ts = 73.406166 last_seq = 260 current_ts = 73.596985 current_received_ts = 73.596245 current_seq = 270 count = 24 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.406883/260, current 73.596985/270: elapsed=190105.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.425903 last_received_ts = 73.596268 last_seq = 261 current_ts = 73.615997 current_received_ts = 73.786407 current_seq = 271 count = 25 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.425903/261, current 73.615997/271: elapsed=190091.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.596985 last_received_ts = 73.596245 last_seq = 270 current_ts = 73.787071 current_received_ts = 73.786369 current_seq = 280 count = 25 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.596985/270, current 73.787071/280: elapsed=190088.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.615997 last_received_ts = 73.786407 last_seq = 271 current_ts = 73.806084 current_received_ts = 73.976486 current_seq = 281 count = 26 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.615997/271, current 73.806084/281: elapsed=190088.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.787071 last_received_ts = 73.786369 last_seq = 280 current_ts = 73.977188 current_received_ts = 73.976456 current_seq = 290 count = 26 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.787071/280, current 73.977188/290: elapsed=190111.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.806084 last_received_ts = 73.976486 last_seq = 281 current_ts = 73.996185 current_received_ts = 74.166565 current_seq = 291 count = 27 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.806084/281, current 73.996185/291: elapsed=190098.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 73.977188 last_received_ts = 73.976456 last_seq = 290 current_ts = 74.167244 current_received_ts = 74.166534 current_seq = 300 count = 27 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 73.977188/290, current 74.167244/300: elapsed=190062.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 73.996185 last_received_ts = 74.166565 last_seq = 291 current_ts = 74.186249 current_received_ts = 74.356613 current_seq = 301 count = 28 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 73.996185/291, current 74.186249/301: elapsed=190069.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 74.167244 last_received_ts = 74.166534 last_seq = 300 current_ts = 74.357315 current_received_ts = 74.356583 current_seq = 310 count = 28 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 74.167244/300, current 74.357315/310: elapsed=190067.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 74.186249 last_received_ts = 74.356613 last_seq = 301 current_ts = 74.376312 current_received_ts = 74.546661 current_seq = 311 count = 29 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 74.186249/301, current 74.376312/311: elapsed=190058.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 74.357315 last_received_ts = 74.356583 last_seq = 310 current_ts = 74.547348 current_received_ts = 74.546638 current_seq = 320 count = 29 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 74.357315/310, current 74.547348/320: elapsed=190036.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 74.376312 last_received_ts = 74.546661 last_seq = 311 current_ts = 74.566353 current_received_ts = 74.736732 current_seq = 321 count = 30 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 74.376312/311, current 74.566353/321: elapsed=190044.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 74.547348 last_received_ts = 74.546638 last_seq = 320 current_ts = 74.737411 current_received_ts = 74.736694 current_seq = 330 count = 30 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 74.547348/320, current 74.737411/330: elapsed=190064.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 74.566353 last_received_ts = 74.736732 last_seq = 321 current_ts = 74.756416 current_received_ts = 74.926765 current_seq = 331 count = 31 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 74.566353/321, current 74.756416/331: elapsed=190066.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 74.737411 last_received_ts = 74.736694 last_seq = 330 current_ts = 74.927452 current_received_ts = 74.926743 current_seq = 340 count = 31 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 74.737411/330, current 74.927452/340: elapsed=190037.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 74.756416 last_received_ts = 74.926765 last_seq = 331 current_ts = 74.946472 current_received_ts = 75.116806 current_seq = 341 count = 32 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 74.756416/331, current 74.946472/341: elapsed=190052.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 74.927452 last_received_ts = 74.926743 last_seq = 340 current_ts = 75.117516 current_received_ts = 75.116783 current_seq = 350 count = 32 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 74.927452/340, current 75.117516/350: elapsed=190061.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 74.946472 last_received_ts = 75.116806 last_seq = 341 current_ts = 75.136513 current_received_ts = 75.306816 current_seq = 351 count = 33 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 74.946472/341, current 75.136513/351: elapsed=190040.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 75.117516 last_received_ts = 75.116783 last_seq = 350 current_ts = 75.307510 current_received_ts = 75.306793 current_seq = 360 count = 33 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 75.117516/350, current 75.307510/360: elapsed=189996.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 75.136513 last_received_ts = 75.306816 last_seq = 351 current_ts = 75.326523 current_received_ts = 75.496834 current_seq = 361 count = 34 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 75.136513/351, current 75.326523/361: elapsed=190012.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 75.307510 last_received_ts = 75.306793 last_seq = 360 current_ts = 75.497520 current_received_ts = 75.496803 current_seq = 370 count = 34 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 75.307510/360, current 75.497520/370: elapsed=190013.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 75.326523 last_received_ts = 75.496834 last_seq = 361 current_ts = 75.516533 current_received_ts = 75.686836 current_seq = 371 count = 35 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 75.326523/361, current 75.516533/371: elapsed=190012.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 75.497520 last_received_ts = 75.496803 last_seq = 370 current_ts = 75.687546 current_received_ts = 75.686813 current_seq = 380 count = 35 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 75.497520/370, current 75.687546/380: elapsed=190027.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 75.516533 last_received_ts = 75.686836 last_seq = 371 current_ts = 75.706551 current_received_ts = 75.876862 current_seq = 381 count = 36 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 75.516533/371, current 75.706551/381: elapsed=190012.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 75.687546 last_received_ts = 75.686813 last_seq = 380 current_ts = 75.877548 current_received_ts = 75.876839 current_seq = 390 count = 36 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 75.687546/380, current 75.877548/390: elapsed=189998.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 75.706551 last_received_ts = 75.876862 last_seq = 381 current_ts = 75.896561 current_received_ts = 76.066864 current_seq = 391 count = 37 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 75.706551/381, current 75.896561/391: elapsed=190013.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 75.877548 last_received_ts = 75.876839 last_seq = 390 current_ts = 76.067574 current_received_ts = 76.066841 current_seq = 400 count = 37 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 75.877548/390, current 76.067574/400: elapsed=190026.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 75.896561 last_received_ts = 76.066864 last_seq = 391 current_ts = 76.086571 current_received_ts = 76.256844 current_seq = 401 count = 38 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 75.896561/391, current 76.086571/401: elapsed=190011.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 76.067574 last_received_ts = 76.066841 last_seq = 400 current_ts = 76.257538 current_received_ts = 76.256821 current_seq = 410 count = 38 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 76.067574/400, current 76.257538/410: elapsed=189965.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 76.086571 last_received_ts = 76.256844 last_seq = 401 current_ts = 76.276550 current_received_ts = 76.446823 current_seq = 411 count = 39 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 76.086571/401, current 76.276550/411: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 76.257538 last_received_ts = 76.256821 last_seq = 410 current_ts = 76.447517 current_received_ts = 76.446800 current_seq = 420 count = 39 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 76.257538/410, current 76.447517/420: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 76.276550 last_received_ts = 76.446823 last_seq = 411 current_ts = 76.466530 current_received_ts = 76.636803 current_seq = 421 count = 40 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 76.276550/411, current 76.466530/421: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 76.447517 last_received_ts = 76.446800 last_seq = 420 current_ts = 76.637489 current_received_ts = 76.636780 current_seq = 430 count = 40 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 76.447517/420, current 76.637489/430: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 76.466530 last_received_ts = 76.636803 last_seq = 421 current_ts = 76.656502 current_received_ts = 76.826775 current_seq = 431 count = 41 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 76.466530/421, current 76.656502/431: elapsed=189977.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 76.637489 last_received_ts = 76.636780 last_seq = 430 current_ts = 76.827484 current_received_ts = 76.826752 current_seq = 440 count = 41 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 76.637489/430, current 76.827484/440: elapsed=189993.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 76.656502 last_received_ts = 76.826775 last_seq = 431 current_ts = 76.846481 current_received_ts = 77.016754 current_seq = 441 count = 42 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 76.656502/431, current 76.846481/441: elapsed=189977.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 76.827484 last_received_ts = 76.826752 last_seq = 440 current_ts = 77.017464 current_received_ts = 77.016731 current_seq = 450 count = 42 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 76.827484/440, current 77.017464/450: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 76.846481 last_received_ts = 77.016754 last_seq = 441 current_ts = 77.036461 current_received_ts = 77.206718 current_seq = 451 count = 43 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 76.846481/441, current 77.036461/451: elapsed=189978.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.017464 last_received_ts = 77.016731 last_seq = 450 current_ts = 77.207420 current_received_ts = 77.206696 current_seq = 460 count = 43 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.017464/450, current 77.207420/460: elapsed=189960.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 77.036461 last_received_ts = 77.206718 last_seq = 451 current_ts = 77.226418 current_received_ts = 77.396629 current_seq = 461 count = 44 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.036461/451, current 77.226418/461: elapsed=189957.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.207420 last_received_ts = 77.206696 last_seq = 460 current_ts = 77.397362 current_received_ts = 77.396614 current_seq = 470 count = 44 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.207420/460, current 77.397362/470: elapsed=189938.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 77.226418 last_received_ts = 77.396629 last_seq = 461 current_ts = 77.416359 current_received_ts = 77.586617 current_seq = 471 count = 45 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.226418/461, current 77.416359/471: elapsed=189944.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.397362 last_received_ts = 77.396614 last_seq = 470 current_ts = 77.587303 current_received_ts = 77.586586 current_seq = 480 count = 45 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.397362/470, current 77.587303/480: elapsed=189941.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 77.416359 last_received_ts = 77.586617 last_seq = 471 current_ts = 77.606300 current_received_ts = 77.776558 current_seq = 481 count = 46 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.416359/471, current 77.606300/481: elapsed=189937.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.587303 last_received_ts = 77.586586 last_seq = 480 current_ts = 77.777252 current_received_ts = 77.776535 current_seq = 490 count = 46 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.587303/480, current 77.777252/490: elapsed=189948.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 77.606300 last_received_ts = 77.776558 last_seq = 481 current_ts = 77.796265 current_received_ts = 77.966507 current_seq = 491 count = 47 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.606300/481, current 77.796265/491: elapsed=189964.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.777252 last_received_ts = 77.776535 last_seq = 490 current_ts = 77.967216 current_received_ts = 77.966484 current_seq = 500 count = 47 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.777252/490, current 77.967216/500: elapsed=189963.0us expected=190436.3us +- 952.2us, error 0.2% (kms_flip:2036) DEBUG: name = flip last_ts = 77.796265 last_received_ts = 77.966507 last_seq = 491 current_ts = 77.986191 current_received_ts = 78.156448 current_seq = 501 count = 48 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.796265/491, current 77.986191/501: elapsed=189931.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 77.967216 last_received_ts = 77.966484 last_seq = 500 current_ts = 78.157135 current_received_ts = 78.156425 current_seq = 510 count = 48 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 77.967216/500, current 78.157135/510: elapsed=189923.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 77.986191 last_received_ts = 78.156448 last_seq = 501 current_ts = 78.176132 current_received_ts = 78.346367 current_seq = 511 count = 49 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 77.986191/501, current 78.176132/511: elapsed=189938.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 78.157135 last_received_ts = 78.156425 last_seq = 510 current_ts = 78.347061 current_received_ts = 78.346344 current_seq = 520 count = 49 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 78.157135/510, current 78.347061/520: elapsed=189922.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 78.176132 last_received_ts = 78.346367 last_seq = 511 current_ts = 78.366051 current_received_ts = 78.536293 current_seq = 521 count = 50 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 78.176132/511, current 78.366051/521: elapsed=189919.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 78.347061 last_received_ts = 78.346344 last_seq = 520 current_ts = 78.536995 current_received_ts = 78.536263 current_seq = 530 count = 50 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 78.347061/520, current 78.536995/530: elapsed=189935.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 78.366051 last_received_ts = 78.536293 last_seq = 521 current_ts = 78.555969 current_received_ts = 78.726212 current_seq = 531 count = 51 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 78.366051/521, current 78.555969/531: elapsed=189922.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 78.536995 last_received_ts = 78.536263 last_seq = 530 current_ts = 78.726898 current_received_ts = 78.726189 current_seq = 540 count = 51 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 78.536995/530, current 78.726898/540: elapsed=189908.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 78.555969 last_received_ts = 78.726212 last_seq = 531 current_ts = 78.745911 current_received_ts = 78.916130 current_seq = 541 count = 52 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 78.555969/531, current 78.745911/541: elapsed=189938.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 78.726898 last_received_ts = 78.726189 last_seq = 540 current_ts = 78.916824 current_received_ts = 78.916107 current_seq = 550 count = 52 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 78.726898/540, current 78.916824/550: elapsed=189921.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 78.745911 last_received_ts = 78.916130 last_seq = 541 current_ts = 78.935829 current_received_ts = 79.106033 current_seq = 551 count = 53 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 78.745911/541, current 78.935829/551: elapsed=189921.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 78.916824 last_received_ts = 78.916107 last_seq = 550 current_ts = 79.106750 current_received_ts = 79.106010 current_seq = 560 count = 53 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 78.916824/550, current 79.106750/560: elapsed=189926.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 78.935829 last_received_ts = 79.106033 last_seq = 551 current_ts = 79.125740 current_received_ts = 79.295921 current_seq = 561 count = 54 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 78.935829/551, current 79.125740/561: elapsed=189907.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 79.106750 last_received_ts = 79.106010 last_seq = 560 current_ts = 79.296646 current_received_ts = 79.295898 current_seq = 570 count = 54 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 79.106750/560, current 79.296646/570: elapsed=189899.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 79.125740 last_received_ts = 79.295921 last_seq = 561 current_ts = 79.315636 current_received_ts = 79.485809 current_seq = 571 count = 55 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 79.125740/561, current 79.315636/571: elapsed=189901.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 79.296646 last_received_ts = 79.295898 last_seq = 570 current_ts = 79.486549 current_received_ts = 79.485794 current_seq = 580 count = 55 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 79.296646/570, current 79.486549/580: elapsed=189900.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 79.315636 last_received_ts = 79.485809 last_seq = 571 current_ts = 79.505539 current_received_ts = 79.675720 current_seq = 581 count = 56 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 79.315636/571, current 79.505539/581: elapsed=189901.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 79.486549 last_received_ts = 79.485794 last_seq = 580 current_ts = 79.676445 current_received_ts = 79.675697 current_seq = 590 count = 56 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 79.486549/580, current 79.676445/590: elapsed=189897.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 79.505539 last_received_ts = 79.675720 last_seq = 581 current_ts = 79.695435 current_received_ts = 79.865623 current_seq = 591 count = 57 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 79.505539/581, current 79.695435/591: elapsed=189895.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 79.676445 last_received_ts = 79.675697 last_seq = 590 current_ts = 79.866348 current_received_ts = 79.865608 current_seq = 600 count = 57 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 79.676445/590, current 79.866348/600: elapsed=189901.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 79.695435 last_received_ts = 79.865623 last_seq = 591 current_ts = 79.885338 current_received_ts = 80.055519 current_seq = 601 count = 58 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 79.695435/591, current 79.885338/601: elapsed=189902.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 79.866348 last_received_ts = 79.865608 last_seq = 600 current_ts = 80.056244 current_received_ts = 80.055504 current_seq = 610 count = 58 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 79.866348/600, current 80.056244/610: elapsed=189898.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 79.885338 last_received_ts = 80.055519 last_seq = 601 current_ts = 80.075233 current_received_ts = 80.245399 current_seq = 611 count = 59 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 79.885338/601, current 80.075233/611: elapsed=189898.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 80.056244 last_received_ts = 80.055504 last_seq = 610 current_ts = 80.246124 current_received_ts = 80.245384 current_seq = 620 count = 59 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 80.056244/610, current 80.246124/620: elapsed=189880.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 80.075233 last_received_ts = 80.245399 last_seq = 611 current_ts = 80.265114 current_received_ts = 80.435318 current_seq = 621 count = 60 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 80.075233/611, current 80.265114/621: elapsed=189879.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 80.246124 last_received_ts = 80.245384 last_seq = 620 current_ts = 80.436012 current_received_ts = 80.435287 current_seq = 630 count = 60 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 80.246124/620, current 80.436012/630: elapsed=189886.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 80.265114 last_received_ts = 80.435318 last_seq = 621 current_ts = 80.454987 current_received_ts = 80.625183 current_seq = 631 count = 61 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 80.265114/621, current 80.454987/631: elapsed=189869.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 80.436012 last_received_ts = 80.435287 last_seq = 630 current_ts = 80.625893 current_received_ts = 80.625160 current_seq = 640 count = 61 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 80.436012/630, current 80.625893/640: elapsed=189879.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 80.454987 last_received_ts = 80.625183 last_seq = 631 current_ts = 80.644875 current_received_ts = 80.815063 current_seq = 641 count = 62 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 80.454987/631, current 80.644875/641: elapsed=189892.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 80.625893 last_received_ts = 80.625160 last_seq = 640 current_ts = 80.815765 current_received_ts = 80.815041 current_seq = 650 count = 62 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 80.625893/640, current 80.815765/650: elapsed=189878.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 80.644875 last_received_ts = 80.815063 last_seq = 641 current_ts = 80.834755 current_received_ts = 81.004944 current_seq = 651 count = 63 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 80.644875/641, current 80.834755/651: elapsed=189879.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 80.815765 last_received_ts = 80.815041 last_seq = 650 current_ts = 81.005630 current_received_ts = 81.004913 current_seq = 660 count = 63 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 80.815765/650, current 81.005630/660: elapsed=189863.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 80.834755 last_received_ts = 81.004944 last_seq = 651 current_ts = 81.024635 current_received_ts = 81.194809 current_seq = 661 count = 64 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 80.834755/651, current 81.024635/661: elapsed=189878.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.005630 last_received_ts = 81.004913 last_seq = 660 current_ts = 81.195511 current_received_ts = 81.194786 current_seq = 670 count = 64 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.005630/660, current 81.195511/670: elapsed=189883.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.024635 last_received_ts = 81.194809 last_seq = 661 current_ts = 81.214500 current_received_ts = 81.384666 current_seq = 671 count = 65 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.024635/661, current 81.214500/671: elapsed=189868.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.195511 last_received_ts = 81.194786 last_seq = 670 current_ts = 81.385361 current_received_ts = 81.384644 current_seq = 680 count = 65 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.195511/670, current 81.385361/680: elapsed=189846.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.214500 last_received_ts = 81.384666 last_seq = 671 current_ts = 81.404358 current_received_ts = 81.574547 current_seq = 681 count = 66 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.214500/671, current 81.404358/681: elapsed=189860.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.385361 last_received_ts = 81.384644 last_seq = 680 current_ts = 81.575233 current_received_ts = 81.574509 current_seq = 690 count = 66 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.385361/680, current 81.575233/690: elapsed=189875.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.404358 last_received_ts = 81.574547 last_seq = 681 current_ts = 81.594208 current_received_ts = 81.764397 current_seq = 691 count = 67 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.404358/681, current 81.594208/691: elapsed=189846.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.575233 last_received_ts = 81.574509 last_seq = 690 current_ts = 81.765083 current_received_ts = 81.764374 current_seq = 700 count = 67 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.575233/690, current 81.765083/700: elapsed=189847.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.594208 last_received_ts = 81.764397 last_seq = 691 current_ts = 81.784065 current_received_ts = 81.954262 current_seq = 701 count = 68 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.594208/691, current 81.784065/701: elapsed=189861.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.765083 last_received_ts = 81.764374 last_seq = 700 current_ts = 81.954941 current_received_ts = 81.954231 current_seq = 710 count = 68 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.765083/700, current 81.954941/710: elapsed=189861.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.784065 last_received_ts = 81.954262 last_seq = 701 current_ts = 81.973930 current_received_ts = 82.144112 current_seq = 711 count = 69 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.784065/701, current 81.973930/711: elapsed=189861.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 81.954941 last_received_ts = 81.954231 last_seq = 710 current_ts = 82.144798 current_received_ts = 82.144089 current_seq = 720 count = 69 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 81.954941/710, current 82.144798/720: elapsed=189856.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 81.973930 last_received_ts = 82.144112 last_seq = 711 current_ts = 82.163780 current_received_ts = 82.333961 current_seq = 721 count = 70 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 81.973930/711, current 82.163780/721: elapsed=189854.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 82.144798 last_received_ts = 82.144089 last_seq = 720 current_ts = 82.334656 current_received_ts = 82.333931 current_seq = 730 count = 70 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 82.144798/720, current 82.334656/730: elapsed=189861.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 82.163780 last_received_ts = 82.333961 last_seq = 721 current_ts = 82.353630 current_received_ts = 82.523819 current_seq = 731 count = 71 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 82.163780/721, current 82.353630/731: elapsed=189846.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 82.334656 last_received_ts = 82.333931 last_seq = 730 current_ts = 82.524498 current_received_ts = 82.523796 current_seq = 740 count = 71 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 82.334656/730, current 82.524498/740: elapsed=189838.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 82.353630 last_received_ts = 82.523819 last_seq = 731 current_ts = 82.543472 current_received_ts = 82.713638 current_seq = 741 count = 72 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 82.353630/731, current 82.543472/741: elapsed=189846.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 82.524498 last_received_ts = 82.523796 last_seq = 740 current_ts = 82.714348 current_received_ts = 82.713615 current_seq = 750 count = 72 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 82.524498/740, current 82.714348/750: elapsed=189853.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 82.543472 last_received_ts = 82.713638 last_seq = 741 current_ts = 82.733337 current_received_ts = 82.903488 current_seq = 751 count = 73 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 82.543472/741, current 82.733337/751: elapsed=189860.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 82.714348 last_received_ts = 82.713615 last_seq = 750 current_ts = 82.904182 current_received_ts = 82.903465 current_seq = 760 count = 73 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 82.714348/750, current 82.904182/760: elapsed=189829.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 82.733337 last_received_ts = 82.903488 last_seq = 751 current_ts = 82.923180 current_received_ts = 83.093330 current_seq = 761 count = 74 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 82.733337/751, current 82.923180/761: elapsed=189845.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 82.904182 last_received_ts = 82.903465 last_seq = 760 current_ts = 83.094040 current_received_ts = 83.093307 current_seq = 770 count = 74 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 82.904182/760, current 83.094040/770: elapsed=189862.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 82.923180 last_received_ts = 83.093330 last_seq = 761 current_ts = 83.113022 current_received_ts = 83.283165 current_seq = 771 count = 75 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 82.923180/761, current 83.113022/771: elapsed=189844.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 83.094040 last_received_ts = 83.093307 last_seq = 770 current_ts = 83.283875 current_received_ts = 83.283142 current_seq = 780 count = 75 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 83.094040/770, current 83.283875/780: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 83.113022 last_received_ts = 83.283165 last_seq = 771 current_ts = 83.302856 current_received_ts = 83.473022 current_seq = 781 count = 76 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 83.113022/771, current 83.302856/781: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 83.283875 last_received_ts = 83.283142 last_seq = 780 current_ts = 83.473701 current_received_ts = 83.472977 current_seq = 790 count = 76 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 83.283875/780, current 83.473701/790: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 83.302856 last_received_ts = 83.473022 last_seq = 781 current_ts = 83.492683 current_received_ts = 83.662834 current_seq = 791 count = 77 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 83.302856/781, current 83.492683/791: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 83.473701 last_received_ts = 83.472977 last_seq = 790 current_ts = 83.663536 current_received_ts = 83.662804 current_seq = 800 count = 77 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 83.473701/790, current 83.663536/800: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = flip last_ts = 83.492683 last_received_ts = 83.662834 last_seq = 791 current_ts = 83.682518 current_received_ts = 83.852661 current_seq = 801 count = 78 seq_step = 1 (kms_flip:2036) DEBUG: flip ts/seq: last 83.492683/791, current 83.682518/801: elapsed=189832.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: name = vblank last_ts = 83.663536 last_received_ts = 83.662804 last_seq = 800 current_ts = 83.853371 current_received_ts = 83.852638 current_seq = 810 count = 78 seq_step = 10 (kms_flip:2036) DEBUG: vblank ts/seq: last 83.663536/800, current 83.853371/810: elapsed=189830.0us expected=190436.3us +- 952.2us, error 0.3% (kms_flip:2036) DEBUG: expected 788, counted 790, encoder type 2 (kms_flip:2036) DEBUG: dropped frames, expected 788, counted 790, encoder type 2 (kms_flip:2036) DEBUG: expected 788, counted 790, encoder type 2 (kms_flip:2036) DEBUG: dropped frames, expected 788, counted 790, encoder type 2 (kms_flip:2036) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2036) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:2036) igt_core-INFO: Stack trace: (kms_flip:2036) igt_core-INFO: #0 ../lib/igt_core.c:2051 __igt_fail_assert() (kms_flip:2036) igt_core-INFO: #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() (kms_flip:2036) igt_core-INFO: #2 ../tests/kms_flip.c:1845 run_test() (kms_flip:2036) igt_core-INFO: #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() (kms_flip:2036) igt_core-INFO: #4 ../tests/kms_flip.c:2001 main() (kms_flip:2036) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2036) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2036) igt_core-INFO: #7 [_start+0x25] END ** Dynamic subtest B-HDMI-A2: FAIL (18.633s) Subtest flip-vs-blocking-wf-vblank: FAIL (26.900s) (kms_flip:2036) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246: (kms_flip:2036) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:2036) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:2036) igt_kms-CRITICAL: DPMS property not found on 154
Dmesg	<6> [59.954986] Console: switching to colour dummy device 80x25 <6> [59.955040] [IGT] kms_flip: executing <7> [59.956725] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [59.957266] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc] <7> [59.957617] i915 0000:00:02.0: [drm:i915_gem_open [i915]] <7> [59.961150] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] <7> [59.961170] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:137:DP-1] <7> [59.966362] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] disconnected <7> [59.966655] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] <7> [59.966666] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:148:HDMI-A-1] <7> [59.971294] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] disconnected <7> [59.971744] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] <7> [59.971756] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:158:DP-2] <7> [59.976225] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] disconnected <7> [59.976495] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] <7> [59.976505] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:164:HDMI-A-3] <7> [59.981179] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] disconnected <7> [59.981556] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] <7> [59.981570] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:137:DP-1] <7> [59.986112] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:137:DP-1] disconnected <7> [59.986150] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] <7> [59.986168] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:148:HDMI-A-1] <7> [59.991044] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:148:HDMI-A-1] disconnected <7> [59.991077] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:154:HDMI-A-2] <7> [59.991094] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:154:HDMI-A-2] <7> [60.017938] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] Supported Monitor Refresh rate range is 0 Hz - 0 Hz <7> [60.017961] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] Assigning EDID-1.4 digital sink color depth as 8 bpc. <7> [60.017968] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] ELD monitor fitHeadless4k <7> [60.017982] i915 0000:00:02.0: [drm:update_display_info.part.0] [CONNECTOR:154:HDMI-A-2] ELD size 36, SAD count 0 <7> [60.018246] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1) <7> [60.018550] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK on first message, retry <7> [60.019076] i915 0000:00:02.0: [drm:do_gmbus_xfer [i915]] GMBUS [i915 gmbus tc1] NAK for addr: 0040 w(1) <7> [60.019375] i915 0000:00:02.0: [drm:drm_dp_dual_mode_detect [drm_display_helper]] DP dual mode HDMI ID: (err -6) <7> [60.020231] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1366x768": 216 268000 1366 1414 1446 1526 768 771 777 814 0x40 0x6 (CLOCK_HIGH) <7> [60.020245] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74250 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE) <7> [60.020255] i915 0000:00:02.0: [drm:drm_mode_prune_invalid] Rejected mode: "1920x1080i": 60 74176 1920 2008 2052 2200 1080 1084 1094 1125 0x40 0x15 (NO_INTERLACE) <7> [60.020267] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:154:HDMI-A-2] probed modes: <7> [60.020291] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [60.020311] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "4088x2304": 10 108000 4088 4152 4280 4536 2304 2305 2308 2340 0x40 0x5 <7> [60.020329] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "3840x2160": 15 128430 3840 3889 3921 4000 2160 2163 2169 2195 0x40 0xa <7> [60.020346] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2880x1800": 11 60000 2880 2888 2984 3040 1800 1802 1820 1845 0x40 0xa <7> [60.020363] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1600": 13 60000 2560 2568 2664 2720 1600 1602 1620 1645 0x40 0xa <7> [60.020379] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2560x1440": 15 60000 2560 2568 2664 2720 1440 1442 1460 1485 0x40 0xa <7> [60.020395] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "2048x1536": 17 60000 2048 2056 2152 2208 1536 1538 1556 1581 0x40 0xa <7> [60.020411] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x1200": 60 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x40 0x5 <7> [60.020426] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1680x1050": 60 146250 1680 1784 1960 2240 1050 1053 1059 1089 0x40 0x6 <7> [60.020442] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1600x900": 60 108000 1600 1624 1704 1800 900 901 904 1000 0x40 0x5 <7> [60.020458] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x1024": 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5 <7> [60.020473] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1360x768": 60 84963 1366 1431 1567 1776 768 771 781 798 0x0 0x6 <7> [60.020490] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1360x768": 20 25190 1360 1368 1464 1520 768 770 788 813 0x40 0xa <7> [60.020506] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x800": 60 83500 1280 1352 1480 1680 800 803 809 831 0x40 0x6 <7> [60.020521] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1152x864": 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5 <7> [60.020537] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5 <7> [60.020552] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74250 1280 1390 1430 1650 720 725 730 750 0x40 0x5 <7> [60.020566] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1280x720": 60 74176 1280 1390 1430 1650 720 725 730 750 0x40 0x5 <7> [60.020581] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 75 78750 1024 1040 1136 1312 768 769 772 800 0x40 0x5 <7> [60.020596] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa <7> [60.020612] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x480": 60 54054 1440 1472 1596 1716 480 489 495 525 0x40 0xa <7> [60.020637] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1440x480": 60 54000 1440 1472 1596 1716 480 489 495 525 0x40 0xa <7> [60.020657] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "1024x576": 60 46560 1024 1064 1160 1296 576 579 584 599 0x0 0x6 <7> [60.020672] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "832x624": 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa <7> [60.020687] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5 <7> [60.020703] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "800x600": 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5 <7> [60.020718] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 75 31500 640 656 720 840 480 481 484 500 0x40 0xa <7> [60.020734] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25200 640 656 752 800 480 490 492 525 0x40 0xa <7> [60.020749] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa <7> [60.020765] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "640x480": 60 25175 640 656 752 800 480 490 492 525 0x40 0xa <7> [60.020780] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] Probed mode: "720x400": 70 28320 720 738 846 900 400 412 414 449 0x40 0x6 <7> [60.020836] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] <7> [60.020853] i915 0000:00:02.0: [drm:intel_dp_detect [i915]] [CONNECTOR:158:DP-2] <7> [60.025623] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:158:DP-2] disconnected <7> [60.025655] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] <7> [60.025670] i915 0000:00:02.0: [drm:intel_hdmi_detect [i915]] [CONNECTOR:164:HDMI-A-3] <7> [60.030542] i915 0000:00:02.0: [drm:drm_helper_probe_single_connector_modes [drm_kms_helper]] [CONNECTOR:164:HDMI-A-3] disconnected <6> [60.031168] [IGT] kms_flip: starting subtest flip-vs-blocking-wf-vblank <6> [60.032928] [IGT] kms_flip: starting dynamic subtest A-HDMI-A2 <7> [60.033288] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:167] <7> [60.033362] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:171] <7> [60.093374] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A] <7> [60.093807] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [60.094247] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [60.094537] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [60.094848] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [60.095125] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [60.095404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [60.095680] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [60.095980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0) <7> [60.096258] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [60.096531] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0) <7> [60.096838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [60.097112] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0) <7> [60.097386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [60.097659] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [60.097958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [60.098187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [60.098410] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [60.098630] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [60.098861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [60.099083] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0) <7> [60.099299] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [60.099515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0) <7> [60.099742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [60.099958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0) <7> [60.100174] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [60.100391] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [60.100606] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [60.100832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [60.101056] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [60.101239] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [60.101403] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [60.101565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [60.101733] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [60.101898] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [60.102059] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0) <7> [60.102224] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0) <7> [60.102390] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0) <7> [60.102554] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [60.102725] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0 <7> [60.102913] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no <7> [60.103070] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [60.103265] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0 <7> [60.103424] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0 <7> [60.103579] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [60.103746] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [60.103900] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [60.104057] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [60.104200] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0 <7> [60.104355] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0 <7> [60.104527] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [60.104740] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [60.104897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset] <7> [60.105052] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no <7> [60.105204] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [60.105355] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [60.105506] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [60.105670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [60.105821] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [60.119726] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [60.138351] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1 <7> [60.139272] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A] <7> [60.140115] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0 <7> [60.140792] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A] <7> [60.141501] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B] <7> [60.142107] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A] <7> [60.142703] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B] <7> [60.143319] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C] <7> [60.143935] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D] <7> [60.144514] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A] <7> [60.145066] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B] <7> [60.145644] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2] <7> [60.146459] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [60.147032] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3 <7> [60.147793] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A] <7> [60.149566] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B] <7> [60.149861] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A] <7> [60.149912] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2] <7> [60.150037] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36) <7> [60.150463] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24) <7> [60.150809] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [60.151222] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A] <7> [60.151581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [60.151957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [60.152248] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [60.152526] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7> [60.152811] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [60.153094] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [60.153386] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [60.153667] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360) <7> [60.153942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [60.154226] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360) <7> [60.154499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [60.154771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096) <7> [60.155034] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [60.155241] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [60.155433] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [60.155623] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [60.155812] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [60.156003] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [60.156204] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [60.156394] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360) <7> [60.156591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [60.156786] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360) <7> [60.156979] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [60.157183] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096) <7> [60.157378] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [60.157568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [60.157759] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [60.157948] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [60.158143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [60.158292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [60.158440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [60.158589] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [60.158738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [60.158887] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [60.159036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929) <7> [60.159198] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929) <7> [60.159348] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929) <7> [60.159498] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [60.159671] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no <7> [60.159820] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x1 <7> [60.159995] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016 <7> [60.160147] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32 <7> [60.160290] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm <7> [60.160433] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 <7> [60.160578] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 <7> [60.160725] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 <7> [60.160870] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1 <7> [60.161026] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560 <7> [60.161187] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1 <7> [60.161352] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz) <7> [60.161509] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz) <7> [60.161665] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [60.161818] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [60.161971] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0 <7> [60.162130] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0 <7> [60.162285] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset] <7> [60.162441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [60.162595] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [60.162746] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [60.162897] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [60.163060] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [60.163212] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [60.163361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0 <7> [60.163510] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [60.163660] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2 <7> [60.163809] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [60.163969] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [60.164146] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [60.164299] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5 <7> [60.164450] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [60.164601] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929 <7> [60.164750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0 <7> [60.164899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [60.165054] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [60.165203] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [60.165350] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, 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> [60.165504] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [60.165659] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [60.165811] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [60.165962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.166126] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.166277] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.166426] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [60.166576] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [60.166725] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.166874] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.167030] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [60.167183] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [60.167332] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:167] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [60.167482] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [60.167630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [60.167780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [60.167928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [60.168091] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [60.168239] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [60.168391] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [60.169779] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3 <7> [60.169965] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B] <7> [60.170117] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A] <7> [60.170266] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B] <7> [60.170412] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C] <7> [60.170557] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D] <7> [60.170703] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A] <7> [60.170852] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B] <7> [60.171038] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3 <7> [60.171231] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A] <7> [60.171382] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7> [60.171968] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1 <7> [60.172156] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [60.172526] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1 <7> [60.172680] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A] <7> [60.191583] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2] <7> [60.191813] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A] <6> [68.295364] [IGT] kms_flip: finished subtest A-HDMI-A2, FAIL <6> [68.295683] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2 <7> [68.295997] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:172] <7> [68.296062] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:173] <7> [68.329923] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A] <7> [68.330098] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected yes, found no) <7> [68.330517] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected yes, found no) <7> [68.330903] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1) <7> [68.331349] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 4, found 0) <7> [68.331736] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [68.332140] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [68.332525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [68.332900] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0) <7> [68.333243] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [68.333548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0) <7> [68.333859] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [68.334175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0) <7> [68.334477] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [68.334778] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [68.335097] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [68.335404] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [68.335708] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [68.335905] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [68.336068] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [68.336222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0) <7> [68.336374] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [68.336525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0) <7> [68.336679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [68.336834] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0) <7> [68.336985] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [68.337143] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [68.337294] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [68.337443] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [68.337593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [68.337742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [68.337892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [68.338065] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [68.338215] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [68.338366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [68.338515] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0) <7> [68.338665] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0) <7> [68.338814] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 139929, found 0) <7> [68.338967] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [68.339132] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0 <7> [68.339304] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no <7> [68.339452] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x1 -> 0x0 <7> [68.339630] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0 <7> [68.339777] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0 <7> [68.339923] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [68.340077] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [68.340221] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [68.340364] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [68.340508] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0 <7> [68.340663] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0 <7> [68.340833] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [68.340991] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [68.341150] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset] <7> [68.341305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no <7> [68.341458] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [68.341609] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [68.341760] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [68.341909] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [68.342064] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [68.350254] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A <7> [68.370923] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1 <7> [68.371423] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A] <7> [68.371955] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0 <7> [68.372427] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A] <7> [68.372922] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B] <7> [68.373398] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A] <7> [68.373888] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B] <7> [68.374357] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C] <7> [68.374875] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D] <7> [68.375355] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A] <7> [68.375827] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B] <7> [68.376259] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2] <7> [68.376760] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1 <7> [68.377246] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3 <7> [68.377692] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A] <7> [68.378557] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B] <7> [68.378894] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B] <7> [68.378937] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2] <7> [68.379035] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36) <7> [68.379379] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24) <7> [68.379676] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [68.380022] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:134:pipe B] <7> [68.380325] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected no, found yes) <7> [68.380646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected no, found yes) <7> [68.380981] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1) <7> [68.381301] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 0, found 4) <7> [68.381626] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [68.381962] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [68.382197] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [68.382430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360) <7> [68.382664] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [68.382909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360) <7> [68.383142] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [68.383373] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096) <7> [68.383605] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [68.383849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [68.384092] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [68.384307] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [68.384527] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [68.384752] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [68.384980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [68.385177] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360) <7> [68.385347] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [68.385523] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360) <7> [68.385692] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [68.385873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096) <7> [68.386049] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [68.386223] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [68.386395] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [68.386566] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [68.386738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [68.386969] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [68.387122] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [68.387274] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [68.387424] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [68.387575] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in dpll_hw_state <7> [68.387725] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected: <7> [68.387882] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x0, cfgcr1: 0x0, 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> [68.388038] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found: <7> [68.388191] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, 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> [68.388344] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [68.388495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929) <7> [68.388646] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929) <7> [68.388803] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 0, found 139929) <7> [68.388955] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset <7> [68.389122] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no <7> [68.389268] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x0 -> 0x2 <7> [68.389442] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016 <7> [68.389588] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32 <7> [68.389735] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm <7> [68.389888] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 <7> [68.390033] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 <7> [68.390176] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 <7> [68.390320] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1 <7> [68.390476] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560 <7> [68.390630] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1 <7> [68.390801] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:84:plane 1B] min cdclk (69965 kHz) > [CRTC:134:pipe B] min cdclk (0 kHz) <7> [68.390958] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz) <7> [68.391113] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [68.391265] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [68.391417] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0 <7> [68.391569] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0 <7> [68.391722] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset] <7> [68.391890] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [68.392044] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0 <7> [68.392195] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [68.392346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [68.392496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [68.392646] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [68.392801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0 <7> [68.392950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [68.393100] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2 <7> [68.393249] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [68.393402] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [68.393556] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [68.393710] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5 <7> [68.393871] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [68.394023] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929 <7> [68.394173] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0 <7> [68.394323] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [68.394471] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [68.394620] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [68.394770] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, 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> [68.394928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [68.395077] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [68.395226] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [68.395375] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.395522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.395670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.395827] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [68.395975] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [68.396125] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.396278] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.396430] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [68.396582] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [68.396733] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [FB:172] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [68.396893] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [68.397043] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [68.397194] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no <7> [68.397343] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no <7> [68.397492] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no <7> [68.397642] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no <7> [68.397798] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no <7> [68.398387] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3 <7> [68.398566] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B] <7> [68.398716] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A] <7> [68.398874] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B] <7> [68.399020] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C] <7> [68.399165] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D] <7> [68.399309] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A] <7> [68.399454] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B] <7> [68.399632] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3 <7> [68.399827] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B] <7> [68.399981] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7> [68.400254] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1 <7> [68.400439] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B <7> [68.420258] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2] <7> [68.420456] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B] <6> [86.929201] [IGT] kms_flip: finished subtest B-HDMI-A2, FAIL <6> [86.930973] [IGT] kms_flip: finished subtest flip-vs-blocking-wf-vblank, FAIL <7> [86.949207] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:172] from all active usage due to RMFB ioctl <7> [86.949435] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:172] is removed <7> [86.949504] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0 <7> [86.949714] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] level wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm -> wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm <7> [86.949890] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] lines 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [86.950070] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] blocks 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [86.950279] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] min_ddb 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 -> 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 <7> [86.950478] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0 <7> [86.950679] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0 <6> [86.961373] [IGT] kms_flip: exiting, ret=98 <7> [86.972936] i915 0000:00:02.0: [drm:compute_baseline_pipe_bpp [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36) <7> [86.973143] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24) <7> [86.973287] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0 <7> [86.973454] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A] <7> [86.973600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.enable (expected no, found yes) <7> [86.973754] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.active (expected no, found yes) <7> [86.973906] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in cpu_transcoder (expected -1, found 0) <7> [86.974061] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in lane_count (expected 0, found 4) <7> [86.974229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000040) <7> [86.974384] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1) <7> [86.974535] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920) <7> [86.974686] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2360) <7> [86.974837] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920) <7> [86.974988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2360) <7> [86.975155] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 2008) <7> [86.975313] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2096) <7> [86.975468] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1080) <7> [86.975620] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1080) <7> [86.975771] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1084) <7> [86.975921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1089) <7> [86.976071] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1125) <7> [86.976231] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1125) <7> [86.976380] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920) <7> [86.976530] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2360) <7> [86.976679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920) <7> [86.976828] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2360) <7> [86.976976] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 2008) <7> [86.977292] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2096) <7> [86.977442] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1080) <7> [86.977591] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1080) <7> [86.977740] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1084) <7> [86.977888] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1089) <7> [86.978036] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1125) <7> [86.978211] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1125) <7> [86.978360] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1) <7> [86.978508] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1) <7> [86.978658] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 0, found 4) <7> [86.978808] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in pipe_bpp (expected 0, found 24) <7> [86.978958] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 139929) <7> [86.979124] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 139929) <7> [86.979277] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:82:pipe A] fastset requirement not met in port_clock (expected 0, found 139929) <7> [86.979430] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset <7> [86.979581] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.enable (expected yes, found no) <7> [86.979732] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.active (expected yes, found no) <7> [86.979881] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1) <7> [86.980031] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in lane_count (expected 4, found 0) <7> [86.980190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in output_types (expected 0x00000040, found 0x00000000) <7> [86.980346] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in framestart_delay (expected 1, found 0) <7> [86.980499] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0) <7> [86.980650] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2360, found 0) <7> [86.980801] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0) <7> [86.980950] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2360, found 0) <7> [86.981100] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 2008, found 0) <7> [86.981259] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2096, found 0) <7> [86.981413] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1080, found 0) <7> [86.981567] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1080, found 0) <7> [86.981719] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1084, found 0) <7> [86.981870] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1089, found 0) <7> [86.982020] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1125, found 0) <7> [86.982182] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1125, found 0) <7> [86.982370] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0) <7> [86.982525] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2360, found 0) <7> [86.982679] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0) <7> [86.982831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2360, found 0) <7> [86.982982] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 2008, found 0) <7> [86.983141] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2096, found 0) <7> [86.983312] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1080, found 0) <7> [86.983462] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1080, found 0) <7> [86.983611] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1084, found 0) <7> [86.983765] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1089, found 0) <7> [86.983918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1125, found 0) <7> [86.984070] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1125, found 0) <7> [86.984232] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pixel_multiplier (expected 1, found 0) <7> [86.984405] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0) <7> [86.984555] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.flags (4) (expected 4, found 0) <7> [86.984705] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in pipe_bpp (expected 24, found 0) <7> [86.984855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 139929, found 0) <7> [86.985022] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 139929, found 0) <7> [86.985195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:134:pipe B] fastset requirement not met in port_clock (expected 139929, found 0) <7> [86.985370] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset <7> [86.985525] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0 <7> [86.985729] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:82:pipe A] dbuf slices 0x0 -> 0x3, ddb (0 - 0) -> (0 - 2048), active pipes 0x2 -> 0x1 <7> [86.985938] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [CRTC:134:pipe B] dbuf slices 0x3 -> 0x0, ddb (0 - 2048) -> (0 - 0), active pipes 0x2 -> 0x1 <7> [86.986224] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016 <7> [86.986430] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32 <7> [86.986579] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] level wm0, wm1, wm2, wm3, wm4, wm5, wm6, wm7, twm, swm, stwm -> wm0,wm1,wm2,wm3,wm4,wm5,wm6,wm7,twm, swm, stwm <7> [86.986724] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] lines 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 1, 2, 2, 2, 2, 5, 8, 9, 0, 0, 0 <7> [86.986868] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] blocks 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 16, 33, 33, 33, 33, 81, 129, 145, 30, 0, 0 <7> [86.987011] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] min_ddb 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 -> 19, 38, 38, 38, 38, 91, 143, 161, 31, 0, 0 <7> [86.987166] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0 <7> [86.987312] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1 <7> [86.987471] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560 <7> [86.987625] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1 <7> [86.987795] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane 1A] min cdclk (69965 kHz) > [CRTC:82:pipe A] min cdclk (0 kHz) <7> [86.987953] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz <7> [86.988114] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0 <7> [86.988267] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0 <7> [86.988421] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0 <7> [86.988578] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset] <7> [86.988734] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB <7> [86.988887] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0 <7> [86.989041] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid> <7> [86.989201] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0 <7> [86.989351] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0 <7> [86.989501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0 <7> [86.989651] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0 <7> [86.989801] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0 <7> [86.989951] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2 <7> [86.990102] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [86.990262] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1080": 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 <7> [86.990413] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [86.990563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1080": 53 139929 1920 2008 2096 2360 1080 1084 1089 1125 0x40 0x5 <7> [86.990714] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=139929, hd=1920 hb=1920-2360 hs=2008-2096 ht=2360, vd=1080 vb=1080-1125 vs=1084-1089 vt=1125, flags=0x5 <7> [86.990864] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929 <7> [86.991013] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0 <7> [86.991170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0 <7> [86.991319] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no <7> [86.991468] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0 <7> [86.991617] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: cfgcr0: 0x1a6655d, 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> [86.991767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0 <7> [86.991916] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries <7> [86.992066] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000 <7> [86.992224] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.992373] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.992522] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.992670] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000 <7> [86.992818] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000 <7> [86.992968] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.993130] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.993279] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000 <7> [86.993429] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000 <7> [86.993576] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [FB:168] 1920x1080 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes <7> [86.993727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0 <7> [86.993875] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0 <7> [86.994025] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no <7> [86.994184] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no <7> [86.994333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no <7> [86.994481] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no <7> [86.994630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no <7> [86.994781] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset] <7> [86.994936] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no <7> [86.995089] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no <7> [86.995308] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no <7> [86.995519] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no <7> [86.995723] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no <7> [86.995905] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no <7> [86.996434] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B <7> [87.015124] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1 <7> [87.015385] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B] <7> [87.015603] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0 <7> [87.015764] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B] <7> [87.015914] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A] <7> [87.016063] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B] <7> [87.016221] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C] <7> [87.016365] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D] <7> [87.016509] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A] <7> [87.016654] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B] <7> [87.016888] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A] <7> [87.017042] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7> [87.017613] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1 <7> [87.017805] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A <7> [87.018280] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1 <7> [87.018434] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A] <7> [87.037434] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2] <7> [87.037897] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A] <7> [87.038849] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B] <7> [87.040048] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0

Created at 2024-12-18 19:20:39