Machine description: shard-snb7
Result:
integration-manifest git-log-oneline i915_display_info6 igt_runner6 run6 runtimes6 results6.json i915_display_info_post_exec6 boot6 dmesg6
Detail | Value |
---|---|
Duration | 10.98 seconds |
Hostname |
shard-snb7 |
Igt-Version |
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.12.0-rc2-CI_DRM_15514-gf3354eab2a45+ x86_64) |
Out |
Starting dynamic subtest: B-VGA1 1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa Expected frametime: 16666us; measured 16837.9us +- 1.746us accuracy 0.03% vblank interval differs from modeline! expected 16665.6us, measured 16838us +- 1.746us, difference 172.3us (98.6 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-VGA1: FAIL (10.979s) |
Err |
Starting dynamic subtest: B-VGA1 (kms_flip:1830) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:1830) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) Dynamic subtest B-VGA1 failed. **** DEBUG **** (kms_flip:1830) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:1830) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=4096) (kms_flip:1830) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:1830) igt_fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=768, format=XR24(0x34325258), modifier=0x0, size=0) (kms_flip:1830) igt_fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096) (kms_flip:1830) ioctl_wrappers-DEBUG: Test requirement passed: igt_has_fb_modifiers(fd) (kms_flip:1830) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:1830) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:1830) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:1830) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:1830) intel_blt-DEBUG: Test requirement passed: cmds_info (kms_flip:1830) igt_fb-DEBUG: Test requirement passed: cairo_surface_status(fb->cairo_surface) == CAIRO_STATUS_SUCCESS (kms_flip:1830) igt_kms-INFO: 1024x768: 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa (kms_flip:1830) DEBUG: No stale events found (kms_flip:1830) i915/intel_drrs-DEBUG: Manually disabling DRRS. 0 (kms_flip:1830) i915/intel_drrs-DEBUG: Test requirement passed: dir >= 0 (kms_flip:1830) INFO: Expected frametime: 16666us; measured 16837.9us +- 1.746us accuracy 0.03% (kms_flip:1830) INFO: vblank interval differs from modeline! expected 16665.6us, measured 16838us +- 1.746us, difference 172.3us (98.6 sigma) (kms_flip:1830) DEBUG: name = flip last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 304.292114 current_received_ts = 304.291534 current_seq = 3402 count = 0 seq_step = 1 (kms_flip:1830) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 304.443634 current_received_ts = 304.443054 current_seq = 3411 count = 0 seq_step = 10 (kms_flip:1830) DEBUG: name = flip last_ts = 304.292114 last_received_ts = 304.291534 last_seq = 3402 current_ts = 304.460480 current_received_ts = 304.459900 current_seq = 3412 count = 1 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 304.292114/3402, current 304.460480/3412: elapsed=168380.0us expected=168378.8us +- 841.9us, error 0.0% (kms_flip:1830) DEBUG: name = vblank last_ts = 304.443634 last_received_ts = 304.443054 last_seq = 3411 current_ts = 304.611969 current_received_ts = 304.611359 current_seq = 3421 count = 1 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 304.443634/3411, current 304.611969/3421: elapsed=168309.0us expected=168378.8us +- 841.9us, error 0.0% (kms_flip:1830) DEBUG: name = flip last_ts = 304.460480 last_received_ts = 304.459900 last_seq = 3412 current_ts = 304.628784 current_received_ts = 304.628174 current_seq = 3422 count = 2 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 304.460480/3412, current 304.628784/3422: elapsed=168294.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 304.611969 last_received_ts = 304.611359 last_seq = 3421 current_ts = 304.780182 current_received_ts = 304.779572 current_seq = 3431 count = 2 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 304.611969/3421, current 304.780182/3431: elapsed=168239.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 304.628784 last_received_ts = 304.628174 last_seq = 3422 current_ts = 304.797028 current_received_ts = 304.796387 current_seq = 3432 count = 3 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 304.628784/3422, current 304.797028/3432: elapsed=168242.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 304.780182 last_received_ts = 304.779572 last_seq = 3431 current_ts = 304.948425 current_received_ts = 304.947815 current_seq = 3441 count = 3 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 304.780182/3431, current 304.948425/3441: elapsed=168238.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 304.797028 last_received_ts = 304.796387 last_seq = 3432 current_ts = 304.965271 current_received_ts = 304.964661 current_seq = 3442 count = 4 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 304.797028/3432, current 304.965271/3442: elapsed=168235.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 304.948425 last_received_ts = 304.947815 last_seq = 3441 current_ts = 305.116669 current_received_ts = 305.116058 current_seq = 3451 count = 4 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 304.948425/3441, current 305.116669/3451: elapsed=168238.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 304.965271 last_received_ts = 304.964661 last_seq = 3442 current_ts = 305.133484 current_received_ts = 305.132904 current_seq = 3452 count = 5 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 304.965271/3442, current 305.133484/3452: elapsed=168238.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.116669 last_received_ts = 305.116058 last_seq = 3451 current_ts = 305.284912 current_received_ts = 305.284302 current_seq = 3461 count = 5 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.116669/3451, current 305.284912/3461: elapsed=168239.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 305.133484 last_received_ts = 305.132904 last_seq = 3452 current_ts = 305.301727 current_received_ts = 305.301117 current_seq = 3462 count = 6 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.133484/3452, current 305.301727/3462: elapsed=168239.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.284912 last_received_ts = 305.284302 last_seq = 3461 current_ts = 305.453156 current_received_ts = 305.452515 current_seq = 3471 count = 6 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.284912/3461, current 305.453156/3471: elapsed=168239.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 305.301727 last_received_ts = 305.301117 last_seq = 3462 current_ts = 305.469971 current_received_ts = 305.469360 current_seq = 3472 count = 7 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.301727/3462, current 305.469971/3472: elapsed=168239.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.453156 last_received_ts = 305.452515 last_seq = 3471 current_ts = 305.621307 current_received_ts = 305.620697 current_seq = 3481 count = 7 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.453156/3471, current 305.621307/3481: elapsed=168169.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = flip last_ts = 305.469971 last_received_ts = 305.469360 last_seq = 3472 current_ts = 305.638123 current_received_ts = 305.637512 current_seq = 3482 count = 8 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.469971/3472, current 305.638123/3482: elapsed=168158.0us expected=168378.8us +- 841.9us, error 0.1% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.621307 last_received_ts = 305.620697 last_seq = 3481 current_ts = 305.789429 current_received_ts = 305.788818 current_seq = 3491 count = 8 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.621307/3481, current 305.789429/3491: elapsed=168115.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 305.638123 last_received_ts = 305.637512 last_seq = 3482 current_ts = 305.806244 current_received_ts = 305.805634 current_seq = 3492 count = 9 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.638123/3482, current 305.806244/3492: elapsed=168114.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.789429 last_received_ts = 305.788818 last_seq = 3491 current_ts = 305.957550 current_received_ts = 305.956909 current_seq = 3501 count = 9 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.789429/3491, current 305.957550/3501: elapsed=168117.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 305.806244 last_received_ts = 305.805634 last_seq = 3492 current_ts = 305.974365 current_received_ts = 305.973724 current_seq = 3502 count = 10 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.806244/3492, current 305.974365/3502: elapsed=168115.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 305.957550 last_received_ts = 305.956909 last_seq = 3501 current_ts = 306.125671 current_received_ts = 306.125092 current_seq = 3511 count = 10 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 305.957550/3501, current 306.125671/3511: elapsed=168114.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 305.974365 last_received_ts = 305.973724 last_seq = 3502 current_ts = 306.142487 current_received_ts = 306.141876 current_seq = 3512 count = 11 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 305.974365/3502, current 306.142487/3512: elapsed=168115.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.125671 last_received_ts = 306.125092 last_seq = 3511 current_ts = 306.293793 current_received_ts = 306.293152 current_seq = 3521 count = 11 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.125671/3511, current 306.293793/3521: elapsed=168116.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.142487 last_received_ts = 306.141876 last_seq = 3512 current_ts = 306.310577 current_received_ts = 306.309998 current_seq = 3522 count = 12 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.142487/3512, current 306.310577/3522: elapsed=168116.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.293793 last_received_ts = 306.293152 last_seq = 3521 current_ts = 306.461884 current_received_ts = 306.461273 current_seq = 3531 count = 12 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.293793/3521, current 306.461884/3531: elapsed=168115.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.310577 last_received_ts = 306.309998 last_seq = 3522 current_ts = 306.478699 current_received_ts = 306.478088 current_seq = 3532 count = 13 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.310577/3522, current 306.478699/3532: elapsed=168115.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.461884 last_received_ts = 306.461273 last_seq = 3531 current_ts = 306.629944 current_received_ts = 306.629333 current_seq = 3541 count = 13 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.461884/3531, current 306.629944/3541: elapsed=168050.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.478699 last_received_ts = 306.478088 last_seq = 3532 current_ts = 306.646759 current_received_ts = 306.646149 current_seq = 3542 count = 14 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.478699/3532, current 306.646759/3542: elapsed=168040.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.629944 last_received_ts = 306.629333 last_seq = 3541 current_ts = 306.797943 current_received_ts = 306.797333 current_seq = 3551 count = 14 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.629944/3541, current 306.797943/3551: elapsed=168007.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.646759 last_received_ts = 306.646149 last_seq = 3542 current_ts = 306.814758 current_received_ts = 306.814117 current_seq = 3552 count = 15 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.646759/3542, current 306.814758/3552: elapsed=168010.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.797943 last_received_ts = 306.797333 last_seq = 3551 current_ts = 306.965973 current_received_ts = 306.965332 current_seq = 3561 count = 15 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.797943/3551, current 306.965973/3561: elapsed=168008.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.814758 last_received_ts = 306.814117 last_seq = 3552 current_ts = 306.982758 current_received_ts = 306.982178 current_seq = 3562 count = 16 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.814758/3552, current 306.982758/3562: elapsed=168005.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 306.965973 last_received_ts = 306.965332 last_seq = 3561 current_ts = 307.133972 current_received_ts = 307.133392 current_seq = 3571 count = 16 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 306.965973/3561, current 307.133972/3571: elapsed=168007.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 306.982758 last_received_ts = 306.982178 last_seq = 3562 current_ts = 307.150757 current_received_ts = 307.150177 current_seq = 3572 count = 17 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 306.982758/3562, current 307.150757/3572: elapsed=168007.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.133972 last_received_ts = 307.133392 last_seq = 3571 current_ts = 307.301971 current_received_ts = 307.301392 current_seq = 3581 count = 17 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.133972/3571, current 307.301971/3581: elapsed=168008.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 307.150757 last_received_ts = 307.150177 last_seq = 3572 current_ts = 307.318787 current_received_ts = 307.318176 current_seq = 3582 count = 18 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.150757/3572, current 307.318787/3582: elapsed=168009.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.301971 last_received_ts = 307.301392 last_seq = 3581 current_ts = 307.469971 current_received_ts = 307.469360 current_seq = 3591 count = 18 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.301971/3581, current 307.469971/3591: elapsed=168008.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = flip last_ts = 307.318787 last_received_ts = 307.318176 last_seq = 3582 current_ts = 307.486786 current_received_ts = 307.486176 current_seq = 3592 count = 19 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.318787/3582, current 307.486786/3592: elapsed=168006.0us expected=168378.8us +- 841.9us, error 0.2% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.469971 last_received_ts = 307.469360 last_seq = 3591 current_ts = 307.637939 current_received_ts = 307.637329 current_seq = 3601 count = 19 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.469971/3591, current 307.637939/3601: elapsed=167943.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 307.486786 last_received_ts = 307.486176 last_seq = 3592 current_ts = 307.654724 current_received_ts = 307.654114 current_seq = 3602 count = 20 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.486786/3592, current 307.654724/3602: elapsed=167936.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.637939 last_received_ts = 307.637329 last_seq = 3601 current_ts = 307.805847 current_received_ts = 307.805237 current_seq = 3611 count = 20 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.637939/3601, current 307.805847/3611: elapsed=167913.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 307.654724 last_received_ts = 307.654114 last_seq = 3602 current_ts = 307.822632 current_received_ts = 307.822052 current_seq = 3612 count = 21 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.654724/3602, current 307.822632/3612: elapsed=167911.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.805847 last_received_ts = 307.805237 last_seq = 3611 current_ts = 307.973755 current_received_ts = 307.973114 current_seq = 3621 count = 21 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.805847/3611, current 307.973755/3621: elapsed=167913.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 307.822632 last_received_ts = 307.822052 last_seq = 3612 current_ts = 307.990540 current_received_ts = 307.989960 current_seq = 3622 count = 22 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.822632/3612, current 307.990540/3622: elapsed=167914.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 307.973755 last_received_ts = 307.973114 last_seq = 3621 current_ts = 308.141663 current_received_ts = 308.141083 current_seq = 3631 count = 22 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 307.973755/3621, current 308.141663/3631: elapsed=167914.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 307.990540 last_received_ts = 307.989960 last_seq = 3622 current_ts = 308.158447 current_received_ts = 308.157867 current_seq = 3632 count = 23 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 307.990540/3622, current 308.158447/3632: elapsed=167914.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.141663 last_received_ts = 308.141083 last_seq = 3631 current_ts = 308.309570 current_received_ts = 308.309052 current_seq = 3641 count = 23 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.141663/3631, current 308.309570/3641: elapsed=167916.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.158447 last_received_ts = 308.157867 last_seq = 3632 current_ts = 308.326355 current_received_ts = 308.325775 current_seq = 3642 count = 24 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.158447/3632, current 308.326355/3642: elapsed=167913.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.309570 last_received_ts = 308.309052 last_seq = 3641 current_ts = 308.477478 current_received_ts = 308.476929 current_seq = 3651 count = 24 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.309570/3641, current 308.477478/3651: elapsed=167910.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.326355 last_received_ts = 308.325775 last_seq = 3642 current_ts = 308.494293 current_received_ts = 308.493713 current_seq = 3652 count = 25 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.326355/3642, current 308.494293/3652: elapsed=167912.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.477478 last_received_ts = 308.476929 last_seq = 3651 current_ts = 308.645355 current_received_ts = 308.644745 current_seq = 3661 count = 25 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.477478/3651, current 308.645355/3661: elapsed=167855.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.494293 last_received_ts = 308.493713 last_seq = 3652 current_ts = 308.662140 current_received_ts = 308.661560 current_seq = 3662 count = 26 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.494293/3652, current 308.662140/3662: elapsed=167848.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.645355 last_received_ts = 308.644745 last_seq = 3661 current_ts = 308.813171 current_received_ts = 308.812592 current_seq = 3671 count = 26 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.645355/3661, current 308.813171/3671: elapsed=167831.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.662140 last_received_ts = 308.661560 last_seq = 3662 current_ts = 308.829956 current_received_ts = 308.829346 current_seq = 3672 count = 27 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.662140/3662, current 308.829956/3672: elapsed=167828.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.813171 last_received_ts = 308.812592 last_seq = 3671 current_ts = 308.981018 current_received_ts = 308.980408 current_seq = 3681 count = 27 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.813171/3671, current 308.981018/3681: elapsed=167830.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.829956 last_received_ts = 308.829346 last_seq = 3672 current_ts = 308.997803 current_received_ts = 308.997162 current_seq = 3682 count = 28 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.829956/3672, current 308.997803/3682: elapsed=167832.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 308.981018 last_received_ts = 308.980408 last_seq = 3681 current_ts = 309.148834 current_received_ts = 309.148224 current_seq = 3691 count = 28 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 308.981018/3681, current 309.148834/3691: elapsed=167831.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 308.997803 last_received_ts = 308.997162 last_seq = 3682 current_ts = 309.165619 current_received_ts = 309.165100 current_seq = 3692 count = 29 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 308.997803/3682, current 309.165619/3692: elapsed=167831.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.148834 last_received_ts = 309.148224 last_seq = 3691 current_ts = 309.316681 current_received_ts = 309.316071 current_seq = 3701 count = 29 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.148834/3691, current 309.316681/3701: elapsed=167832.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 309.165619 last_received_ts = 309.165100 last_seq = 3692 current_ts = 309.333466 current_received_ts = 309.332947 current_seq = 3702 count = 30 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 309.165619/3692, current 309.333466/3702: elapsed=167830.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.316681 last_received_ts = 309.316071 last_seq = 3701 current_ts = 309.484497 current_received_ts = 309.483856 current_seq = 3711 count = 30 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.316681/3701, current 309.484497/3711: elapsed=167827.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = flip last_ts = 309.333466 last_received_ts = 309.332947 last_seq = 3702 current_ts = 309.501282 current_received_ts = 309.500702 current_seq = 3712 count = 31 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 309.333466/3702, current 309.501282/3712: elapsed=167832.0us expected=168378.8us +- 841.9us, error 0.3% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.484497 last_received_ts = 309.483856 last_seq = 3711 current_ts = 309.652283 current_received_ts = 309.651672 current_seq = 3721 count = 31 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.484497/3711, current 309.652283/3721: elapsed=167779.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 309.501282 last_received_ts = 309.500702 last_seq = 3712 current_ts = 309.669067 current_received_ts = 309.668488 current_seq = 3722 count = 32 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 309.501282/3712, current 309.669067/3722: elapsed=167772.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.652283 last_received_ts = 309.651672 last_seq = 3721 current_ts = 309.820038 current_received_ts = 309.819458 current_seq = 3731 count = 32 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.652283/3721, current 309.820038/3731: elapsed=167760.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 309.669067 last_received_ts = 309.668488 last_seq = 3722 current_ts = 309.836823 current_received_ts = 309.836243 current_seq = 3732 count = 33 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 309.669067/3722, current 309.836823/3732: elapsed=167755.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.820038 last_received_ts = 309.819458 last_seq = 3731 current_ts = 309.987793 current_received_ts = 309.987152 current_seq = 3741 count = 33 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.820038/3731, current 309.987793/3741: elapsed=167754.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 309.836823 last_received_ts = 309.836243 last_seq = 3732 current_ts = 310.004578 current_received_ts = 310.003998 current_seq = 3742 count = 34 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 309.836823/3732, current 310.004578/3742: elapsed=167758.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 309.987793 last_received_ts = 309.987152 last_seq = 3741 current_ts = 310.155548 current_received_ts = 310.154968 current_seq = 3751 count = 34 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 309.987793/3741, current 310.155548/3751: elapsed=167761.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.004578 last_received_ts = 310.003998 last_seq = 3742 current_ts = 310.172333 current_received_ts = 310.171722 current_seq = 3752 count = 35 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.004578/3742, current 310.172333/3752: elapsed=167760.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.155548 last_received_ts = 310.154968 last_seq = 3751 current_ts = 310.323303 current_received_ts = 310.322632 current_seq = 3761 count = 35 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.155548/3751, current 310.323303/3761: elapsed=167750.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.172333 last_received_ts = 310.171722 last_seq = 3752 current_ts = 310.340088 current_received_ts = 310.339447 current_seq = 3762 count = 36 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.172333/3752, current 310.340088/3762: elapsed=167758.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.323303 last_received_ts = 310.322632 last_seq = 3761 current_ts = 310.491058 current_received_ts = 310.490417 current_seq = 3771 count = 36 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.323303/3761, current 310.491058/3771: elapsed=167761.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.340088 last_received_ts = 310.339447 last_seq = 3762 current_ts = 310.507843 current_received_ts = 310.507233 current_seq = 3772 count = 37 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.340088/3762, current 310.507843/3772: elapsed=167758.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.491058 last_received_ts = 310.490417 last_seq = 3771 current_ts = 310.658783 current_received_ts = 310.658173 current_seq = 3781 count = 37 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.491058/3771, current 310.658783/3781: elapsed=167714.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.507843 last_received_ts = 310.507233 last_seq = 3772 current_ts = 310.675537 current_received_ts = 310.674957 current_seq = 3782 count = 38 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.507843/3772, current 310.675537/3782: elapsed=167702.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.658783 last_received_ts = 310.658173 last_seq = 3781 current_ts = 310.826477 current_received_ts = 310.825867 current_seq = 3791 count = 38 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.658783/3781, current 310.826477/3791: elapsed=167695.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.675537 last_received_ts = 310.674957 last_seq = 3782 current_ts = 310.843231 current_received_ts = 310.842621 current_seq = 3792 count = 39 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.675537/3782, current 310.843231/3792: elapsed=167694.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.826477 last_received_ts = 310.825867 last_seq = 3791 current_ts = 310.994171 current_received_ts = 310.993500 current_seq = 3801 count = 39 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.826477/3791, current 310.994171/3801: elapsed=167694.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 310.843231 last_received_ts = 310.842621 last_seq = 3792 current_ts = 311.010925 current_received_ts = 311.010345 current_seq = 3802 count = 40 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 310.843231/3792, current 311.010925/3802: elapsed=167697.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 310.994171 last_received_ts = 310.993500 last_seq = 3801 current_ts = 311.161865 current_received_ts = 311.161255 current_seq = 3811 count = 40 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 310.994171/3801, current 311.161865/3811: elapsed=167697.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 311.010925 last_received_ts = 311.010345 last_seq = 3802 current_ts = 311.178619 current_received_ts = 311.178070 current_seq = 3812 count = 41 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 311.010925/3802, current 311.178619/3812: elapsed=167694.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 311.161865 last_received_ts = 311.161255 last_seq = 3811 current_ts = 311.329559 current_received_ts = 311.328949 current_seq = 3821 count = 41 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 311.161865/3811, current 311.329559/3821: elapsed=167695.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 311.178619 last_received_ts = 311.178070 last_seq = 3812 current_ts = 311.346344 current_received_ts = 311.345642 current_seq = 3822 count = 42 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 311.178619/3812, current 311.346344/3822: elapsed=167704.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 311.329559 last_received_ts = 311.328949 last_seq = 3821 current_ts = 311.497253 current_received_ts = 311.496613 current_seq = 3831 count = 42 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 311.329559/3821, current 311.497253/3831: elapsed=167694.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 311.346344 last_received_ts = 311.345642 last_seq = 3822 current_ts = 311.514008 current_received_ts = 311.513397 current_seq = 3832 count = 43 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 311.346344/3822, current 311.514008/3832: elapsed=167687.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 311.497253 last_received_ts = 311.496613 last_seq = 3831 current_ts = 311.664917 current_received_ts = 311.664307 current_seq = 3841 count = 43 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 311.497253/3831, current 311.664917/3841: elapsed=167652.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = flip last_ts = 311.514008 last_received_ts = 311.513397 last_seq = 3832 current_ts = 311.681671 current_received_ts = 311.681091 current_seq = 3842 count = 44 seq_step = 1 (kms_flip:1830) DEBUG: flip ts/seq: last 311.514008/3832, current 311.681671/3842: elapsed=167640.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: name = vblank last_ts = 311.664917 last_received_ts = 311.664307 last_seq = 3841 current_ts = 311.832550 current_received_ts = 311.831909 current_seq = 3851 count = 44 seq_step = 10 (kms_flip:1830) DEBUG: vblank ts/seq: last 311.664917/3841, current 311.832550/3851: elapsed=167639.0us expected=168378.8us +- 841.9us, error 0.4% (kms_flip:1830) DEBUG: expected 448, counted 450, encoder type 1 (kms_flip:1830) DEBUG: dropped frames, expected 448, counted 450, encoder type 1 (kms_flip:1830) DEBUG: expected 448, counted 450, encoder type 1 (kms_flip:1830) DEBUG: dropped frames, expected 448, counted 450, encoder type 1 (kms_flip:1830) CRITICAL: Test assertion failure function __run_test_on_crtc_set, file ../tests/kms_flip.c:1674: (kms_flip:1830) CRITICAL: Failed assertion: !retried && needs_retry_after_link_reset(mon) (kms_flip:1830) igt_core-INFO: Stack trace: (kms_flip:1830) igt_core-INFO: #0 ../lib/igt_core.c:2051 __igt_fail_assert() (kms_flip:1830) igt_core-INFO: #1 ../tests/kms_flip.c:791 run_test_on_crtc_set.constprop.0() (kms_flip:1830) igt_core-INFO: #2 ../tests/kms_flip.c:1845 run_test() (kms_flip:1830) igt_core-INFO: #3 ../tests/kms_flip.c:2078 __igt_unique____real_main2001() (kms_flip:1830) igt_core-INFO: #4 ../tests/kms_flip.c:2001 main() (kms_flip:1830) igt_core-INFO: #5 [__libc_init_first+0x8a] (kms_flip:1830) igt_core-INFO: #6 [__libc_start_main+0x8b] (kms_flip:1830) igt_core-INFO: #7 [_start+0x25] **** END **** Dynamic subtest B-VGA1: FAIL (10.979s) |
Dmesg |
<6> [303.465624] [IGT] kms_flip: starting dynamic subtest B-VGA1
<7> [303.465960] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:84]
<7> [303.466036] i915 0000:00:02.0: [drm:drm_mode_addfb2] [FB:85]
<7> [303.499443] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:47:pipe A]
<7> [303.499564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [303.499983] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [303.500281] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [303.500502] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in has_pch_encoder (expected yes, found no)
<7> [303.500785] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_lanes (expected 1, found 0)
<7> [303.501011] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in fdi_m_n (expected tu 64 data 6039797/8388608 link 125829/524288, found tu 0, data 0/0 link 0/0)
<7> [303.501303] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [303.501559] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [303.501772] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [303.502053] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [303.502282] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [303.502465] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [303.502698] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [303.502963] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [303.503186] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [303.503412] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [303.503618] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [303.503819] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [303.504035] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [303.504217] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [303.504398] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [303.504579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [303.504760] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [303.504974] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [303.505195] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [303.505376] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [303.505557] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [303.505738] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [303.505930] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [303.506157] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [303.506339] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [303.506519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [303.506700] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [303.506892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [303.507110] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [303.507293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in pipe_bpp (expected 24, found 0)
<7> [303.507474] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64800, found 0)
<7> [303.507654] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64800, found 0)
<7> [303.507858] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe A] fastset requirement not met in port_clock (expected 64800, found 0)
<7> [303.508074] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe A] fastset requirement not met, forcing full modeset
<7> [303.508255] i915 0000:00:02.0: [drm:intel_unreference_shared_dpll_crtc [i915]] [CRTC:47:pipe A] releasing PCH DPLL A
<7> [303.508451] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [303.508634] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [303.508836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe A] enable: no [modeset]
<7> [303.509035] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:primary A] fb: [NOFB], visible: no
<7> [303.509364] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [303.519784] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port B found
<7> [303.520170] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port C found
<7> [303.520544] i915 0000:00:02.0: [drm:g4x_dp_port_enabled [i915]] No pipe for DP port D found
<7> [303.536985] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disable PCH DPLL A (active 0x1, on? 1) for [CRTC:47:pipe A]
<7> [303.537258] i915 0000:00:02.0: [drm:intel_disable_shared_dpll [i915]] disabling PCH DPLL A
<7> [303.537730] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [303.537989] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [303.538321] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [303.538684] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [303.539146] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe A]
<7> [303.539694] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [303.539864] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CRTC:63:pipe B]
<7> [303.539895] i915 0000:00:02.0: [drm:drm_mode_setcrtc] [CONNECTOR:64:VGA-1]
<7> [303.539982] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CONNECTOR:64:VGA-1] Limiting display bpp to 24 (EDID bpp 0, max requested bpp 0, max platform bpp 36)
<7> [303.540297] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [303.540596] i915 0000:00:02.0: [drm:intel_fdi_atomic_check_link [i915]] checking fdi config on pipe B, lanes 1
<7> [303.540872] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.enable (expected no, found yes)
<7> [303.541153] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.active (expected no, found yes)
<7> [303.541372] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in cpu_transcoder (expected -1, found 1)
<7> [303.541593] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in has_pch_encoder (expected no, found yes)
<7> [303.541776] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_lanes (expected 0, found 1)
<7> [303.541960] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in fdi_m_n (expected tu 0 data 0/0 link 0/0, found tu 64, data 6039797/8388608 link 125829/524288)
<7> [303.542249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [303.542558] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [303.542842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [303.543069] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [303.543252] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [303.543440] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [303.543691] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [303.543988] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [303.544293] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [303.544547] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [303.544742] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<7> [303.544942] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [303.545229] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [303.545534] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [303.545775] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [303.545954] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [303.546130] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [303.546366] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [303.546684] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [303.546980] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [303.547200] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [303.547409] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [303.547596] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [303.547796] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [303.548080] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [303.548367] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [303.548624] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [303.548838] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [303.549032] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [303.549233] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in dpll_hw_state
<7> [303.549552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [303.549849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0xc4010001, dpll_md: 0x0, fp0: 0x30f06, fp1: 0x30f06
<7> [303.550067] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [303.550253] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [303.550430] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in pipe_bpp (expected 0, found 24)
<7> [303.550697] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64800)
<7> [303.551002] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64800)
<7> [303.551268] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:63:pipe B] fastset requirement not met in port_clock (expected 0, found 64800)
<7> [303.551468] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:63:pipe B] fastset requirement not met, forcing full modeset
<7> [303.551696] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:48:primary B] min cdclk (64800 kHz) > [CRTC:63:pipe B] min cdclk (0 kHz)
<7> [303.551887] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 400000 kHz, actual 400000 kHz
<7> [303.552173] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [303.552480] i915 0000:00:02.0: [drm:intel_find_shared_dpll [i915]] [CRTC:63:pipe B] allocated PCH DPLL A
<7> [303.552750] i915 0000:00:02.0: [drm:intel_reference_shared_dpll_crtc [i915]] [CRTC:63:pipe B] reserving PCH DPLL A
<7> [303.552961] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:63:pipe B] enable: yes [modeset]
<7> [303.553140] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [303.553318] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: B, pipe bpp: 24, dithering: 0
<7> [303.553531] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [303.553727] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [303.553904] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [303.554080] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [303.554257] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] fdi: lanes: 1; data_m: 6039797, data_n: 8388608, link_m: 125829, link_n: 524288, tu: 64
<7> [303.554451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [303.554639] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [303.554851] 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> [303.555044] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [303.555221] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [303.555398] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [303.555594] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64800 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [303.555788] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] crtc timings: clock=64800, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [303.555966] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64800, pipe src: 1024x768+0+0, pixel rate 64800
<7> [303.556142] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [303.556349] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pch pfit: 0x0+0+0, disabled, force thru: no
<7> [303.556563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [303.556755] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x84100010, dpll_md: 0x0, fp0: 0x10c09, fp1: 0x10c09
<7> [303.556932] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x3 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [303.557108] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [303.557284] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: pre offsets: 0x0000 0x0000 0x0000
<7> [303.557499] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [303.557510] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [303.557688] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [303.557888] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe csc: coefficients: 0x0000 0x0000 0x0000
<7> [303.558069] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:48:primary B] fb: [FB:84] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [303.558251] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [303.558439] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [303.559496] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [303.559692] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:65:CRT]
<7> [303.559867] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:66:HDMI D]
<7> [303.560039] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:74:DP D]
<7> [303.560774] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe B
<7> [303.561794] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x100
<7> [303.561969] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 1 done.
<7> [303.562836] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI_RX_IIR 0x200
<7> [303.563040] i915 0000:00:02.0: [drm:gen6_fdi_link_train [i915]] FDI train 2 done.
<7> [303.563227] i915 0000:00:02.0: [drm:ilk_pch_enable [i915]] FDI train done.
<7> [303.563426] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enable PCH DPLL A (active 0x2, on? 0) for [CRTC:63:pipe B]
<7> [303.563645] i915 0000:00:02.0: [drm:intel_enable_shared_dpll [i915]] enabling PCH DPLL A
<7> [303.615573] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:64:VGA-1]
<7> [303.616039] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:63:pipe B]
<6> [314.444650] [IGT] kms_flip: finished subtest B-VGA1, FAIL
|