Machine description: shard-rkl-3
Result:
git-log-oneline i915_display_info11 igt_runner11 runtimes11 results11.json boot11 dmesg11
Detail | Value |
---|---|
Duration | 18.62 seconds |
Hostname |
shard-rkl-3 |
Igt-Version |
IGT-Version: 1.29-g6b9242949 (x86_64) (Linux: 6.13.0-rc1-CI_DRM_15799-ge57b4b7cd137+ x86_64) |
Out |
Starting dynamic subtest: B-HDMI-A2 1920x1080: 53 139930 1920 2008 2096 2360 1080 1084 1089 1125 0x48 0x5 Expected frametime: 18974us; measured 19066.1us +- 6.115us accuracy 0.10% vblank interval differs from modeline! expected 18973.8us, measured 19066us +- 6.115us, difference 92.3us (15.1 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.624s) Subtest flip-vs-blocking-wf-vblank: FAIL (25.369s) 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 dynamic subtest: B-HDMI-A2 (kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest B-HDMI-A2 failed. **** DEBUG **** (kms_flip:2073) DEBUG: name = flip last_ts = 79.339211 last_received_ts = 79.509750 last_seq = 204 current_ts = 79.529495 current_received_ts = 79.700043 current_seq = 214 count = 19 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 79.339211/204, current 79.529495/214: elapsed=190284.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 79.510468 last_received_ts = 79.509727 last_seq = 213 current_ts = 79.700752 current_received_ts = 79.700012 current_seq = 223 count = 19 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 79.510468/213, current 79.700752/223: elapsed=190286.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 79.529495 last_received_ts = 79.700043 last_seq = 214 current_ts = 79.719788 current_received_ts = 79.890327 current_seq = 224 count = 20 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 79.529495/214, current 79.719788/224: elapsed=190287.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 79.700752 last_received_ts = 79.700012 last_seq = 223 current_ts = 79.891045 current_received_ts = 79.890305 current_seq = 233 count = 20 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 79.700752/223, current 79.891045/233: elapsed=190285.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 79.719788 last_received_ts = 79.890327 last_seq = 224 current_ts = 79.910065 current_received_ts = 80.080605 current_seq = 234 count = 21 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 79.719788/224, current 79.910065/234: elapsed=190283.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 79.891045 last_received_ts = 79.890305 last_seq = 233 current_ts = 80.081322 current_received_ts = 80.080582 current_seq = 243 count = 21 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 79.891045/233, current 80.081322/243: elapsed=190283.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 79.910065 last_received_ts = 80.080605 last_seq = 234 current_ts = 80.100357 current_received_ts = 80.270828 current_seq = 244 count = 22 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 79.910065/234, current 80.100357/244: elapsed=190287.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 80.081322 last_received_ts = 80.080582 last_seq = 243 current_ts = 80.271545 current_received_ts = 80.270813 current_seq = 253 count = 22 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 80.081322/243, current 80.271545/253: elapsed=190225.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 80.100357 last_received_ts = 80.270828 last_seq = 244 current_ts = 80.290573 current_received_ts = 80.461090 current_seq = 254 count = 23 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 80.100357/244, current 80.290573/254: elapsed=190218.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 80.271545 last_received_ts = 80.270813 last_seq = 253 current_ts = 80.461769 current_received_ts = 80.461044 current_seq = 263 count = 23 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 80.271545/253, current 80.461769/263: elapsed=190221.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 80.290573 last_received_ts = 80.461090 last_seq = 254 current_ts = 80.480782 current_received_ts = 80.651283 current_seq = 264 count = 24 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 80.290573/254, current 80.480782/264: elapsed=190207.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 80.461769 last_received_ts = 80.461044 last_seq = 263 current_ts = 80.651985 current_received_ts = 80.651253 current_seq = 273 count = 24 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 80.461769/263, current 80.651985/273: elapsed=190216.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 80.480782 last_received_ts = 80.651283 last_seq = 264 current_ts = 80.670998 current_received_ts = 80.841499 current_seq = 274 count = 25 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 80.480782/264, current 80.670998/274: elapsed=190218.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 80.651985 last_received_ts = 80.651253 last_seq = 273 current_ts = 80.842201 current_received_ts = 80.841469 current_seq = 283 count = 25 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 80.651985/273, current 80.842201/283: elapsed=190217.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 80.670998 last_received_ts = 80.841499 last_seq = 274 current_ts = 80.861206 current_received_ts = 81.031715 current_seq = 284 count = 26 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 80.670998/274, current 80.861206/284: elapsed=190212.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 80.842201 last_received_ts = 80.841469 last_seq = 283 current_ts = 81.032417 current_received_ts = 81.031685 current_seq = 293 count = 26 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 80.842201/283, current 81.032417/293: elapsed=190215.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = flip last_ts = 80.861206 last_received_ts = 81.031715 last_seq = 284 current_ts = 81.051430 current_received_ts = 81.221901 current_seq = 294 count = 27 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 80.861206/284, current 81.051430/294: elapsed=190221.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.032417 last_received_ts = 81.031685 last_seq = 293 current_ts = 81.222595 current_received_ts = 81.221878 current_seq = 303 count = 27 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.032417/293, current 81.222595/303: elapsed=190181.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 81.051430 last_received_ts = 81.221901 last_seq = 294 current_ts = 81.241615 current_received_ts = 81.412064 current_seq = 304 count = 28 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 81.051430/294, current 81.241615/304: elapsed=190186.0us expected=190660.6us +- 953.3us, error 0.2% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.222595 last_received_ts = 81.221878 last_seq = 303 current_ts = 81.412743 current_received_ts = 81.412033 current_seq = 313 count = 28 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.222595/303, current 81.412743/313: elapsed=190141.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 81.241615 last_received_ts = 81.412064 last_seq = 304 current_ts = 81.431755 current_received_ts = 81.602211 current_seq = 314 count = 29 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 81.241615/304, current 81.431755/314: elapsed=190140.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.412743 last_received_ts = 81.412033 last_seq = 313 current_ts = 81.602898 current_received_ts = 81.602180 current_seq = 323 count = 29 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.412743/313, current 81.602898/323: elapsed=190156.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 81.431755 last_received_ts = 81.602211 last_seq = 314 current_ts = 81.621925 current_received_ts = 81.792366 current_seq = 324 count = 30 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 81.431755/314, current 81.621925/324: elapsed=190172.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.602898 last_received_ts = 81.602180 last_seq = 323 current_ts = 81.793068 current_received_ts = 81.792336 current_seq = 333 count = 30 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.602898/323, current 81.793068/333: elapsed=190173.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 81.621925 last_received_ts = 81.792366 last_seq = 324 current_ts = 81.812073 current_received_ts = 81.982529 current_seq = 334 count = 31 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 81.621925/324, current 81.812073/334: elapsed=190142.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.793068 last_received_ts = 81.792336 last_seq = 333 current_ts = 81.983208 current_received_ts = 81.982498 current_seq = 343 count = 31 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.793068/333, current 81.983208/343: elapsed=190140.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 81.812073 last_received_ts = 81.982529 last_seq = 334 current_ts = 82.002228 current_received_ts = 82.172668 current_seq = 344 count = 32 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 81.812073/334, current 82.002228/344: elapsed=190155.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 81.983208 last_received_ts = 81.982498 last_seq = 343 current_ts = 82.173347 current_received_ts = 82.172638 current_seq = 353 count = 32 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 81.983208/343, current 82.173347/353: elapsed=190139.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.002228 last_received_ts = 82.172668 last_seq = 344 current_ts = 82.192360 current_received_ts = 82.362770 current_seq = 354 count = 33 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.002228/344, current 82.192360/354: elapsed=190133.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 82.173347 last_received_ts = 82.172638 last_seq = 353 current_ts = 82.363457 current_received_ts = 82.362747 current_seq = 363 count = 33 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 82.173347/353, current 82.363457/363: elapsed=190105.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.192360 last_received_ts = 82.362770 last_seq = 354 current_ts = 82.382477 current_received_ts = 82.552872 current_seq = 364 count = 34 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.192360/354, current 82.382477/364: elapsed=190120.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 82.363457 last_received_ts = 82.362747 last_seq = 363 current_ts = 82.553558 current_received_ts = 82.552841 current_seq = 373 count = 34 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 82.363457/363, current 82.553558/373: elapsed=190103.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.382477 last_received_ts = 82.552872 last_seq = 364 current_ts = 82.572563 current_received_ts = 82.742966 current_seq = 374 count = 35 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.382477/364, current 82.572563/374: elapsed=190088.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 82.553558 last_received_ts = 82.552841 last_seq = 373 current_ts = 82.743675 current_received_ts = 82.742943 current_seq = 383 count = 35 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 82.553558/373, current 82.743675/383: elapsed=190119.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.572563 last_received_ts = 82.742966 last_seq = 374 current_ts = 82.762672 current_received_ts = 82.933083 current_seq = 384 count = 36 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.572563/374, current 82.762672/384: elapsed=190104.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 82.743675 last_received_ts = 82.742943 last_seq = 383 current_ts = 82.933762 current_received_ts = 82.933052 current_seq = 393 count = 36 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 82.743675/383, current 82.933762/393: elapsed=190089.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.762672 last_received_ts = 82.933083 last_seq = 384 current_ts = 82.952774 current_received_ts = 83.123177 current_seq = 394 count = 37 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.762672/384, current 82.952774/394: elapsed=190105.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 82.933762 last_received_ts = 82.933052 last_seq = 393 current_ts = 83.123863 current_received_ts = 83.123146 current_seq = 403 count = 37 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 82.933762/393, current 83.123863/403: elapsed=190099.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 82.952774 last_received_ts = 83.123177 last_seq = 394 current_ts = 83.142883 current_received_ts = 83.313232 current_seq = 404 count = 38 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 82.952774/394, current 83.142883/404: elapsed=190110.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 83.123863 last_received_ts = 83.123146 last_seq = 403 current_ts = 83.313919 current_received_ts = 83.313210 current_seq = 413 count = 38 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 83.123863/403, current 83.313919/413: elapsed=190057.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 83.142883 last_received_ts = 83.313232 last_seq = 404 current_ts = 83.332947 current_received_ts = 83.503288 current_seq = 414 count = 39 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 83.142883/404, current 83.332947/414: elapsed=190058.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 83.313919 last_received_ts = 83.313210 last_seq = 413 current_ts = 83.503983 current_received_ts = 83.503265 current_seq = 423 count = 39 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 83.313919/413, current 83.503983/423: elapsed=190060.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 83.332947 last_received_ts = 83.503288 last_seq = 414 current_ts = 83.522987 current_received_ts = 83.693344 current_seq = 424 count = 40 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 83.332947/414, current 83.522987/424: elapsed=190046.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 83.503983 last_received_ts = 83.503265 last_seq = 423 current_ts = 83.694054 current_received_ts = 83.693321 current_seq = 433 count = 40 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 83.503983/423, current 83.694054/433: elapsed=190073.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 83.522987 last_received_ts = 83.693344 last_seq = 424 current_ts = 83.713043 current_received_ts = 83.883415 current_seq = 434 count = 41 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 83.522987/424, current 83.713043/434: elapsed=190054.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 83.694054 last_received_ts = 83.693321 last_seq = 433 current_ts = 83.884094 current_received_ts = 83.883392 current_seq = 443 count = 41 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 83.694054/433, current 83.884094/443: elapsed=190045.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 83.713043 last_received_ts = 83.883415 last_seq = 434 current_ts = 83.903099 current_received_ts = 84.073471 current_seq = 444 count = 42 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 83.713043/434, current 83.903099/444: elapsed=190059.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 83.884094 last_received_ts = 83.883392 last_seq = 443 current_ts = 84.074150 current_received_ts = 84.073441 current_seq = 453 count = 42 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 83.884094/443, current 84.074150/453: elapsed=190055.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 83.903099 last_received_ts = 84.073471 last_seq = 444 current_ts = 84.093163 current_received_ts = 84.263496 current_seq = 454 count = 43 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 83.903099/444, current 84.093163/454: elapsed=190057.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 84.074150 last_received_ts = 84.073441 last_seq = 453 current_ts = 84.264175 current_received_ts = 84.263466 current_seq = 463 count = 43 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 84.074150/453, current 84.264175/463: elapsed=190025.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 84.093163 last_received_ts = 84.263496 last_seq = 454 current_ts = 84.283180 current_received_ts = 84.453514 current_seq = 464 count = 44 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 84.093163/454, current 84.283180/464: elapsed=190020.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 84.264175 last_received_ts = 84.263466 last_seq = 463 current_ts = 84.454208 current_received_ts = 84.453484 current_seq = 473 count = 44 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 84.264175/463, current 84.454208/473: elapsed=190033.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 84.283180 last_received_ts = 84.453514 last_seq = 464 current_ts = 84.473198 current_received_ts = 84.643524 current_seq = 474 count = 45 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 84.283180/464, current 84.473198/474: elapsed=190020.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 84.454208 last_received_ts = 84.453484 last_seq = 473 current_ts = 84.644234 current_received_ts = 84.643501 current_seq = 483 count = 45 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 84.454208/473, current 84.644234/483: elapsed=190019.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 84.473198 last_received_ts = 84.643524 last_seq = 474 current_ts = 84.663216 current_received_ts = 84.833542 current_seq = 484 count = 46 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 84.473198/474, current 84.663216/484: elapsed=190018.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 84.644234 last_received_ts = 84.643501 last_seq = 483 current_ts = 84.834251 current_received_ts = 84.833519 current_seq = 493 count = 46 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 84.644234/483, current 84.834251/493: elapsed=190018.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 84.663216 last_received_ts = 84.833542 last_seq = 484 current_ts = 84.853249 current_received_ts = 85.023560 current_seq = 494 count = 47 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 84.663216/484, current 84.853249/494: elapsed=190032.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 84.834251 last_received_ts = 84.833519 last_seq = 493 current_ts = 85.024246 current_received_ts = 85.023537 current_seq = 503 count = 47 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 84.834251/493, current 85.024246/503: elapsed=190002.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 84.853249 last_received_ts = 85.023560 last_seq = 494 current_ts = 85.043251 current_received_ts = 85.213554 current_seq = 504 count = 48 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 84.853249/494, current 85.043251/504: elapsed=190004.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.024246 last_received_ts = 85.023537 last_seq = 503 current_ts = 85.214264 current_received_ts = 85.213531 current_seq = 513 count = 48 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.024246/503, current 85.214264/513: elapsed=190014.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = flip last_ts = 85.043251 last_received_ts = 85.213554 last_seq = 504 current_ts = 85.233261 current_received_ts = 85.403534 current_seq = 514 count = 49 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.043251/504, current 85.233261/514: elapsed=190009.0us expected=190660.6us +- 953.3us, error 0.3% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.214264 last_received_ts = 85.213531 last_seq = 513 current_ts = 85.404243 current_received_ts = 85.403519 current_seq = 523 count = 49 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.214264/513, current 85.404243/523: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 85.233261 last_received_ts = 85.403534 last_seq = 514 current_ts = 85.423233 current_received_ts = 85.593521 current_seq = 524 count = 50 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.233261/514, current 85.423233/524: elapsed=189970.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.404243 last_received_ts = 85.403519 last_seq = 523 current_ts = 85.594231 current_received_ts = 85.593498 current_seq = 533 count = 50 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.404243/523, current 85.594231/533: elapsed=189984.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 85.423233 last_received_ts = 85.593521 last_seq = 524 current_ts = 85.613213 current_received_ts = 85.783501 current_seq = 534 count = 51 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.423233/524, current 85.613213/534: elapsed=189982.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.594231 last_received_ts = 85.593498 last_seq = 533 current_ts = 85.784210 current_received_ts = 85.783485 current_seq = 543 count = 51 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.594231/533, current 85.784210/543: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 85.613213 last_received_ts = 85.783501 last_seq = 534 current_ts = 85.803200 current_received_ts = 85.973488 current_seq = 544 count = 52 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.613213/534, current 85.803200/544: elapsed=189984.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.784210 last_received_ts = 85.783485 last_seq = 543 current_ts = 85.974182 current_received_ts = 85.973465 current_seq = 553 count = 52 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.784210/543, current 85.974182/553: elapsed=189968.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 85.803200 last_received_ts = 85.973488 last_seq = 544 current_ts = 85.993179 current_received_ts = 86.163467 current_seq = 554 count = 53 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.803200/544, current 85.993179/554: elapsed=189983.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 85.974182 last_received_ts = 85.973465 last_seq = 553 current_ts = 86.164154 current_received_ts = 86.163445 current_seq = 563 count = 53 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 85.974182/553, current 86.164154/563: elapsed=189974.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 85.993179 last_received_ts = 86.163467 last_seq = 554 current_ts = 86.183151 current_received_ts = 86.353416 current_seq = 564 count = 54 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 85.993179/554, current 86.183151/564: elapsed=189971.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 86.164154 last_received_ts = 86.163445 last_seq = 563 current_ts = 86.354126 current_received_ts = 86.353394 current_seq = 573 count = 54 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 86.164154/563, current 86.354126/573: elapsed=189967.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 86.183151 last_received_ts = 86.353416 last_seq = 564 current_ts = 86.373100 current_received_ts = 86.543365 current_seq = 574 count = 55 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 86.183151/564, current 86.373100/574: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 86.354126 last_received_ts = 86.353394 last_seq = 573 current_ts = 86.544060 current_received_ts = 86.543343 current_seq = 583 count = 55 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 86.354126/573, current 86.544060/583: elapsed=189937.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 86.373100 last_received_ts = 86.543365 last_seq = 574 current_ts = 86.563072 current_received_ts = 86.733315 current_seq = 584 count = 56 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 86.373100/574, current 86.563072/584: elapsed=189967.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 86.544060 last_received_ts = 86.543343 last_seq = 583 current_ts = 86.734032 current_received_ts = 86.733299 current_seq = 593 count = 56 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 86.544060/583, current 86.734032/593: elapsed=189968.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 86.563072 last_received_ts = 86.733315 last_seq = 584 current_ts = 86.753021 current_received_ts = 86.923271 current_seq = 594 count = 57 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 86.563072/584, current 86.753021/594: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 86.734032 last_received_ts = 86.733299 last_seq = 593 current_ts = 86.923981 current_received_ts = 86.923248 current_seq = 603 count = 57 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 86.734032/593, current 86.923981/603: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 86.753021 last_received_ts = 86.923271 last_seq = 594 current_ts = 86.942963 current_received_ts = 87.113220 current_seq = 604 count = 58 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 86.753021/594, current 86.942963/604: elapsed=189938.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 86.923981 last_received_ts = 86.923248 last_seq = 603 current_ts = 87.113930 current_received_ts = 87.113197 current_seq = 613 count = 58 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 86.923981/603, current 87.113930/613: elapsed=189952.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 86.942963 last_received_ts = 87.113220 last_seq = 604 current_ts = 87.132912 current_received_ts = 87.303146 current_seq = 614 count = 59 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 86.942963/604, current 87.132912/614: elapsed=189950.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 87.113930 last_received_ts = 87.113197 last_seq = 613 current_ts = 87.303856 current_received_ts = 87.303123 current_seq = 623 count = 59 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 87.113930/613, current 87.303856/623: elapsed=189927.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 87.132912 last_received_ts = 87.303146 last_seq = 614 current_ts = 87.322853 current_received_ts = 87.493073 current_seq = 624 count = 60 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 87.132912/614, current 87.322853/624: elapsed=189940.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 87.303856 last_received_ts = 87.303123 last_seq = 623 current_ts = 87.493782 current_received_ts = 87.493050 current_seq = 633 count = 60 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 87.303856/623, current 87.493782/633: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 87.322853 last_received_ts = 87.493073 last_seq = 624 current_ts = 87.512779 current_received_ts = 87.683022 current_seq = 634 count = 61 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 87.322853/624, current 87.512779/634: elapsed=189926.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 87.493782 last_received_ts = 87.493050 last_seq = 633 current_ts = 87.683693 current_received_ts = 87.682983 current_seq = 643 count = 61 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 87.493782/633, current 87.683693/643: elapsed=189910.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 87.512779 last_received_ts = 87.683022 last_seq = 634 current_ts = 87.702705 current_received_ts = 87.872940 current_seq = 644 count = 62 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 87.512779/634, current 87.702705/644: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 87.683693 last_received_ts = 87.682983 last_seq = 643 current_ts = 87.873619 current_received_ts = 87.872910 current_seq = 653 count = 62 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 87.683693/643, current 87.873619/653: elapsed=189927.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 87.702705 last_received_ts = 87.872940 last_seq = 644 current_ts = 87.892616 current_received_ts = 88.062859 current_seq = 654 count = 63 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 87.702705/644, current 87.892616/654: elapsed=189912.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 87.873619 last_received_ts = 87.872910 last_seq = 653 current_ts = 88.063545 current_received_ts = 88.062836 current_seq = 663 count = 63 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 87.873619/653, current 88.063545/663: elapsed=189925.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 87.892616 last_received_ts = 88.062859 last_seq = 654 current_ts = 88.082535 current_received_ts = 88.252762 current_seq = 664 count = 64 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 87.892616/654, current 88.082535/664: elapsed=189924.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 88.063545 last_received_ts = 88.062836 last_seq = 663 current_ts = 88.253456 current_received_ts = 88.252739 current_seq = 673 count = 64 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 88.063545/663, current 88.253456/673: elapsed=189907.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 88.082535 last_received_ts = 88.252762 last_seq = 664 current_ts = 88.272461 current_received_ts = 88.442665 current_seq = 674 count = 65 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 88.082535/664, current 88.272461/674: elapsed=189921.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 88.253456 last_received_ts = 88.252739 last_seq = 673 current_ts = 88.443367 current_received_ts = 88.442642 current_seq = 683 count = 65 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 88.253456/673, current 88.443367/683: elapsed=189917.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 88.272461 last_received_ts = 88.442665 last_seq = 674 current_ts = 88.462364 current_received_ts = 88.632568 current_seq = 684 count = 66 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 88.272461/674, current 88.462364/684: elapsed=189902.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 88.443367 last_received_ts = 88.442642 last_seq = 683 current_ts = 88.633255 current_received_ts = 88.632545 current_seq = 693 count = 66 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 88.443367/683, current 88.633255/693: elapsed=189888.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 88.462364 last_received_ts = 88.632568 last_seq = 684 current_ts = 88.652260 current_received_ts = 88.822472 current_seq = 694 count = 67 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 88.462364/684, current 88.652260/694: elapsed=189902.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 88.633255 last_received_ts = 88.632545 last_seq = 693 current_ts = 88.823158 current_received_ts = 88.822449 current_seq = 703 count = 67 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 88.633255/693, current 88.823158/703: elapsed=189901.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 88.652260 last_received_ts = 88.822472 last_seq = 694 current_ts = 88.842148 current_received_ts = 89.012367 current_seq = 704 count = 68 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 88.652260/694, current 88.842148/704: elapsed=189887.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 88.823158 last_received_ts = 88.822449 last_seq = 703 current_ts = 89.013077 current_received_ts = 89.012344 current_seq = 713 count = 68 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 88.823158/703, current 89.013077/713: elapsed=189917.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 88.842148 last_received_ts = 89.012367 last_seq = 704 current_ts = 89.032051 current_received_ts = 89.202263 current_seq = 714 count = 69 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 88.842148/704, current 89.032051/714: elapsed=189901.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.013077 last_received_ts = 89.012344 last_seq = 713 current_ts = 89.202957 current_received_ts = 89.202240 current_seq = 723 count = 69 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.013077/713, current 89.202957/723: elapsed=189878.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.032051 last_received_ts = 89.202263 last_seq = 714 current_ts = 89.221939 current_received_ts = 89.392105 current_seq = 724 count = 70 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.032051/714, current 89.221939/724: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.202957 last_received_ts = 89.202240 last_seq = 723 current_ts = 89.392845 current_received_ts = 89.392097 current_seq = 733 count = 70 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.202957/723, current 89.392845/733: elapsed=189888.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.221939 last_received_ts = 89.392105 last_seq = 724 current_ts = 89.411835 current_received_ts = 89.582054 current_seq = 734 count = 71 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.221939/724, current 89.411835/734: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.392845 last_received_ts = 89.392097 last_seq = 733 current_ts = 89.582718 current_received_ts = 89.582024 current_seq = 743 count = 71 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.392845/733, current 89.582718/743: elapsed=189874.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.411835 last_received_ts = 89.582054 last_seq = 734 current_ts = 89.601723 current_received_ts = 89.771912 current_seq = 744 count = 72 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.411835/734, current 89.601723/744: elapsed=189890.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.582718 last_received_ts = 89.582024 last_seq = 743 current_ts = 89.772598 current_received_ts = 89.771889 current_seq = 753 count = 72 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.582718/743, current 89.772598/753: elapsed=189882.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.601723 last_received_ts = 89.771912 last_seq = 744 current_ts = 89.791603 current_received_ts = 89.961792 current_seq = 754 count = 73 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.601723/744, current 89.791603/754: elapsed=189881.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.772598 last_received_ts = 89.771889 last_seq = 753 current_ts = 89.962479 current_received_ts = 89.961769 current_seq = 763 count = 73 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.772598/753, current 89.962479/763: elapsed=189881.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.791603 last_received_ts = 89.961792 last_seq = 754 current_ts = 89.981468 current_received_ts = 90.151703 current_seq = 764 count = 74 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.791603/754, current 89.981468/764: elapsed=189865.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 89.962479 last_received_ts = 89.961769 last_seq = 763 current_ts = 90.152390 current_received_ts = 90.151680 current_seq = 773 count = 74 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 89.962479/763, current 90.152390/773: elapsed=189911.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 89.981468 last_received_ts = 90.151703 last_seq = 764 current_ts = 90.171387 current_received_ts = 90.341660 current_seq = 774 count = 75 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 89.981468/764, current 90.171387/774: elapsed=189920.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 90.152390 last_received_ts = 90.151680 last_seq = 773 current_ts = 90.342369 current_received_ts = 90.341637 current_seq = 783 count = 75 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 90.152390/773, current 90.342369/783: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 90.171387 last_received_ts = 90.341660 last_seq = 774 current_ts = 90.361366 current_received_ts = 90.531631 current_seq = 784 count = 76 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 90.171387/774, current 90.361366/784: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 90.342369 last_received_ts = 90.341637 last_seq = 783 current_ts = 90.532318 current_received_ts = 90.531609 current_seq = 793 count = 76 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 90.342369/783, current 90.532318/793: elapsed=189947.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 90.361366 last_received_ts = 90.531631 last_seq = 784 current_ts = 90.551315 current_received_ts = 90.721588 current_seq = 794 count = 77 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 90.361366/784, current 90.551315/794: elapsed=189946.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 90.532318 last_received_ts = 90.531609 last_seq = 793 current_ts = 90.722298 current_received_ts = 90.721565 current_seq = 803 count = 77 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 90.532318/793, current 90.722298/803: elapsed=189979.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = flip last_ts = 90.551315 last_received_ts = 90.721588 last_seq = 794 current_ts = 90.741272 current_received_ts = 90.911552 current_seq = 804 count = 78 seq_step = 1 (kms_flip:2073) DEBUG: flip ts/seq: last 90.551315/794, current 90.741272/804: elapsed=189963.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: name = vblank last_ts = 90.722298 last_received_ts = 90.721565 last_seq = 803 current_ts = 90.912254 current_received_ts = 90.911530 current_seq = 813 count = 78 seq_step = 10 (kms_flip:2073) DEBUG: vblank ts/seq: last 90.722298/803, current 90.912254/813: elapsed=189962.0us expected=190660.6us +- 953.3us, error 0.4% (kms_flip:2073) DEBUG: expected 787, counted 790, encoder type 2 (kms_flip:2073) DEBUG: dropped frames, expected 787, counted 790, encoder type 2 (kms_flip:2073) DEBUG: expected 787, counted 790, encoder type 2 (kms_flip:2073) DEBUG: dropped frames, expected 787, counted 790, encoder type 2 (kms_flip:2073) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:2073) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:2073) igt_core-INFO: Stack trace: (kms_flip:2073) igt_core-INFO: #0 ../lib/igt_core.c:2051 __igt_fail_assert() (kms_flip:2073) igt_core-INFO: #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() (kms_flip:2073) igt_core-INFO: #2 ../tests/kms_flip.c:1845 run_test() (kms_flip:2073) igt_core-INFO: #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() (kms_flip:2073) igt_core-INFO: #4 ../tests/kms_flip.c:2001 main() (kms_flip:2073) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:2073) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:2073) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest B-HDMI-A2: FAIL (18.624s) Subtest flip-vs-blocking-wf-vblank: FAIL (25.369s) (kms_flip:2073) igt_kms-CRITICAL: Test assertion failure function kmstest_set_connector_dpms, file ../lib/igt_kms.c:2246: (kms_flip:2073) igt_kms-CRITICAL: Failed assertion: found_it (kms_flip:2073) igt_kms-CRITICAL: Last errno: 9, Bad file descriptor (kms_flip:2073) igt_kms-CRITICAL: DPMS property not found on 154 |
Dmesg |
<6> [75.094929] [IGT] kms_flip: starting dynamic subtest B-HDMI-A2
<7> [75.095251] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:172]
<7> [75.095320] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:173]
<7> [75.129395] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:82:pipe A]
<7> [75.129554] 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> [75.130015] 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> [75.130473] 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> [75.130885] 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> [75.131313] 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> [75.131714] 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> [75.132130] 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> [75.132526] 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> [75.132882] 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> [75.133218] 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> [75.133537] 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> [75.133856] 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> [75.134197] 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> [75.134516] 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> [75.134832] 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> [75.135171] 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> [75.135487] 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> [75.135789] 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> [75.136044] 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> [75.136289] 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> [75.136446] 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> [75.136604] 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> [75.136764] 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> [75.136923] 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> [75.137103] 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> [75.137264] 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> [75.137421] 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> [75.137579] 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> [75.137736] 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> [75.137893] 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> [75.138065] 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> [75.138223] 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> [75.138381] 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> [75.138538] 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> [75.138695] 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> [75.138857] 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> [75.139030] 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> [75.139194] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [75.139356] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] releasing DPLL 0
<7> [75.139538] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x3 -> 0x1 (total dbuf slices 0x3), mbus joined? no->no
<7> [75.139696] 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> [75.139887] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [75.140052] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [75.140208] 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> [75.140368] 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> [75.140528] 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> [75.140686] 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> [75.140843] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 0 num active planes 0
<7> [75.141015] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<7> [75.141194] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [75.141357] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [75.141521] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: no [modeset]
<7> [75.141683] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane 1A] fb: [NOFB], visible: no
<7> [75.141846] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [75.142012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [75.142172] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [75.142338] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [75.142501] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [75.154102] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [75.173494] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [75.174191] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x1, on? 1) for [CRTC:82:pipe A]
<7> [75.174899] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [75.175541] i915 0000:00:02.0: [drm:__intel_fbc_disable [i915]] Disabling FBC on [PLANE:32:plane 1A]
<7> [75.176206] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [75.176833] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [75.177457] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [75.178087] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [75.178663] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [75.178824] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [75.178981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [75.179136] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [75.179344] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x1
<7> [75.179550] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [75.179736] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [75.180091] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [75.180216] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:134:pipe B]
<7> [75.180236] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:154:HDMI-A-2]
<7> [75.180286] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [75.180464] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [75.180620] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [75.180785] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:134:pipe B]
<7> [75.180952] 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> [75.181116] 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> [75.181284] 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> [75.181450] 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> [75.181612] 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> [75.181773] 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> [75.181941] 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> [75.182101] 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> [75.182260] 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> [75.182419] 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> [75.182578] 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> [75.182737] 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> [75.182901] 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> [75.183059] 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> [75.183245] 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> [75.183410] 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> [75.183572] 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> [75.183733] 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> [75.183909] 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> [75.184069] 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> [75.184228] 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> [75.184387] 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> [75.184578] 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> [75.184822] 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> [75.185052] 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> [75.185273] 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> [75.185493] 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> [75.185714] 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> [75.185949] 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> [75.186170] 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> [75.186390] 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> [75.186618] 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> [75.186786] 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> [75.186948] 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> [75.187109] 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> [75.187271] 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> [75.187432] 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> [75.187593] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [75.187777] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] Enabled dbuf slices 0x1 -> 0x3 (total dbuf slices 0x3), mbus joined? no->no
<7> [75.187939] 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> [75.188148] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [75.188313] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [75.188475] 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> [75.188635] 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> [75.188845] 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> [75.189005] 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> [75.189164] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 559716 num active planes 1
<7> [75.189333] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [75.189501] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [75.189689] 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> [75.189863] i915 0000:00:02.0: [drm:intel_bw_calc_min_cdclk [i915]] new bandwidth min cdclk (10932 kHz) > old min cdclk (0 kHz)
<7> [75.190028] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [75.190189] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [75.190350] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:134:pipe B] allocated DPLL 0
<7> [75.190513] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] reserving DPLL 0
<7> [75.190674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: yes [modeset]
<7> [75.190845] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [75.191012] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [75.191175] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [75.191336] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [75.191496] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [75.191655] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [75.191838] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [75.191996] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [75.192155] 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> [75.192313] 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> [75.192473] 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> [75.192632] 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> [75.192800] 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> [75.192958] 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> [75.193116] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [75.193274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [75.193430] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [75.193589] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [75.193754] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [75.193911] 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> [75.194070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [75.194227] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [75.194385] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [75.194543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.194700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.194866] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195022] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [75.195178] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [75.195333] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195489] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195645] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [75.195824] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [75.195980] 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> [75.196138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [75.196294] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [75.196452] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [75.196609] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [75.196780] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [75.196944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [75.197106] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [75.197697] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [75.197904] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [75.198064] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [75.198222] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [75.198379] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [75.198535] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [75.198690] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [75.198855] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [75.199044] i915 0000:00:02.0: [drm:gen9_dbuf_slices_update [i915]] Updating dbuf slices to 0x3
<7> [75.199242] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x2, on? 0) for [CRTC:134:pipe B]
<7> [75.199406] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [75.199621] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [75.199826] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [75.219660] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [75.219862] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<6> [93.718967] [IGT] kms_flip: finished subtest B-HDMI-A2, FAIL
<6> [93.720373] [IGT] kms_flip: finished subtest flip-vs-blocking-wf-vblank, FAIL
<7> [93.737089] i915 0000:00:02.0: [drm:drm_mode_rmfb_work_fn] Removing [FB:172] from all active usage due to RMFB ioctl
<7> [93.737369] i915 0000:00:02.0: [drm:atomic_remove_fb] Disabling [PLANE:84:plane 1B] because [FB:172] is removed
<7> [93.737454] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:84:plane 1B] ddb ( 0 - 2016) -> ( 0 - 0), size 2016 -> 0
<7> [93.737785] 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> [93.738061] 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> [93.738333] 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> [93.738619] 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> [93.738885] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:134:pipe B] data rate 0 num active planes 0
<7> [93.739164] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 0
<6> [93.762344] [IGT] kms_flip: exiting, ret=98
<7> [93.777177] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:154:HDMI-A-2] Limiting display bpp to 24 (EDID bpp 24, max requested bpp 36, max platform bpp 36)
<7> [93.777385] i915 0000:00:02.0: [drm:intel_hdmi_compute_clock [i915]] picking 8 bpc for HDMI output (pipe bpp: 24)
<7> [93.777560] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [93.777743] i915 0000:00:02.0: [drm:intel_ddi_compute_config_late [i915]] [ENCODER:153:DDI TC1/PHY C] [CRTC:82:pipe A]
<7> [93.777909] 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> [93.778080] 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> [93.778248] 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> [93.778413] 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> [93.778585] 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> [93.778749] 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> [93.778910] 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> [93.779071] 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> [93.779233] 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> [93.779393] 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> [93.779570] 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> [93.779735] 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> [93.779898] 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> [93.780059] 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> [93.780219] 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> [93.780379] 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> [93.780710] 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> [93.780871] 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> [93.781031] 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> [93.781190] 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> [93.781349] 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> [93.781524] 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> [93.781683] 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> [93.781841] 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> [93.781999] 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> [93.782156] 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> [93.782314] 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> [93.782482] 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> [93.782640] 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> [93.782798] 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> [93.782955] 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> [93.783114] 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> [93.783272] 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> [93.783431] 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> [93.783602] 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> [93.783760] 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> [93.783919] 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> [93.784077] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:82:pipe A] fastset requirement not met, forcing full modeset
<7> [93.784236] 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> [93.784393] 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> [93.784560] 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> [93.784718] 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> [93.784876] 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> [93.785035] 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> [93.785194] 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> [93.785354] 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> [93.785519] 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> [93.785678] 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> [93.785836] 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> [93.785995] 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> [93.786154] 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> [93.786311] 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> [93.786475] 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> [93.786632] 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> [93.786790] 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> [93.786948] 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> [93.787106] 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> [93.787263] 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> [93.787420] 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> [93.787590] 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> [93.787748] 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> [93.787906] 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> [93.788064] 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> [93.788221] 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> [93.788378] 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> [93.788552] 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> [93.788710] 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> [93.788868] 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> [93.789027] 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> [93.789184] 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> [93.789342] 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> [93.789514] 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> [93.789673] 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> [93.789832] 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> [93.789990] 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> [93.790148] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:134:pipe B] fastset requirement not met, forcing full modeset
<7> [93.790306] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:134:pipe B] releasing DPLL 0
<7> [93.790535] 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> [93.790767] 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> [93.791026] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:32:plane 1A] ddb ( 0 - 0) -> ( 0 - 2016), size 0 -> 2016
<7> [93.791250] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:77:cursor A] ddb ( 0 - 0) -> (2016 - 2048), size 0 -> 32
<7> [93.791495] 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> [93.791720] 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> [93.791943] 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> [93.792110] 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> [93.792269] i915 0000:00:02.0: [drm:skl_compute_wm [i915]] [PLANE:129:cursor B] ddb (2016 - 2048) -> ( 0 - 0), size 32 -> 0
<7> [93.792436] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] [CRTC:82:pipe A] data rate 559716 num active planes 1
<7> [93.792607] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] QGV point 0: max bw 16761 required 560
<7> [93.792775] i915 0000:00:02.0: [drm:intel_bw_atomic_check [i915]] No SAGV, using single QGV point mask 0x1
<7> [93.792954] 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> [93.793121] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 180000 kHz, actual 180000 kHz
<7> [93.793282] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [93.793454] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:82:pipe A] allocated DPLL 0
<7> [93.793620] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:82:pipe A] reserving DPLL 0
<7> [93.793784] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:82:pipe A] enable: yes [modeset]
<7> [93.793947] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: HDMI (0x40), output format: RGB, sink format: RGB
<7> [93.794108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [93.794269] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [93.794441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [93.794605] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [93.794767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [93.794928] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [93.795087] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [93.795247] 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> [93.795407] 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> [93.795580] 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> [93.795739] 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> [93.795899] 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> [93.796059] 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> [93.796217] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 139929, pipe src: 1920x1080+0+0, pixel rate 139929
<7> [93.796374] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 135, ips linetime: 0
<7> [93.796543] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] num_scalers: 2, scaler_users: 0x0, scaler_id: -1, scaling_filter: 0
<7> [93.796700] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [93.796858] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [93.797015] 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> [93.797179] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [93.797346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [93.797547] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: pre offsets: 0x0000 0x0000 0x0000
<7> [93.797708] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.797869] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798029] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798188] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] output csc: post offsets: 0x0000 0x0000 0x0000
<7> [93.798348] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [93.798515] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798674] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798832] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [93.798990] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: post offsets: 0x0000 0x0000 0x0000
<7> [93.799147] 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> [93.799305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [93.799472] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1080.000000+0.000000+0.000000 dst: 1920x1080+0+0
<7> [93.799630] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:plane 2A] fb: [NOFB], visible: no
<7> [93.799788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:50:plane 3A] fb: [NOFB], visible: no
<7> [93.799944] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:59:plane 4A] fb: [NOFB], visible: no
<7> [93.800101] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:68:plane 5A] fb: [NOFB], visible: no
<7> [93.800257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:77:cursor A] fb: [NOFB], visible: no
<7> [93.800413] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:134:pipe B] enable: no [modeset]
<7> [93.800581] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:84:plane 1B] fb: [NOFB], visible: no
<7> [93.800739] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:93:plane 2B] fb: [NOFB], visible: no
<7> [93.800895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:102:plane 3B] fb: [NOFB], visible: no
<7> [93.801051] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:111:plane 4B] fb: [NOFB], visible: no
<7> [93.801207] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:120:plane 5B] fb: [NOFB], visible: no
<7> [93.801363] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:129:cursor B] fb: [NOFB], visible: no
<7> [93.801666] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe B
<7> [93.814429] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DDI_IO_TC1
<7> [93.814617] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable DPLL 0 (active 0x2, on? 1) for [CRTC:134:pipe B]
<7> [93.814804] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling DPLL 0
<7> [93.814976] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:136:DDI B/PHY B]
<7> [93.815137] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:138:DP-MST A]
<7> [93.815296] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:139:DP-MST B]
<7> [93.815464] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:153:DDI TC1/PHY C]
<7> [93.815624] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:157:DDI TC2/PHY D]
<7> [93.815786] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:159:DP-MST A]
<7> [93.815952] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:160:DP-MST B]
<7> [93.816165] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 0x1, on? 0) for [CRTC:82:pipe A]
<7> [93.816331] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0
<7> [93.816560] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DDI_IO_TC1
<7> [93.816759] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [93.817135] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] reserved 17694720 bytes of contiguous stolen space for FBC, limit: 1
<7> [93.817301] i915 0000:00:02.0: [drm:intel_fbc_update [i915]] Enabling FBC on [PLANE:32:plane 1A]
<7> [93.836204] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:154:HDMI-A-2]
<7> [93.836426] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:82:pipe A]
<7> [93.836890] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:134:pipe B]
<7> [93.837402] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
|