<html> <head> <base href="https://bugs.freedesktop.org/"> </head> <body> <div> <a class="bz_bug_link bz_status_ASSIGNED " title="ASSIGNED - [All] igt/kms_flip subcases vblank-vs-suspend and vblank-vs-suspend-interruptible fail" href="https://bugs.freedesktop.org/show_bug.cgi?id=79052#c36">Comment # 36</a> on <a class="bz_bug_link bz_status_ASSIGNED " title="ASSIGNED - [All] igt/kms_flip subcases vblank-vs-suspend and vblank-vs-suspend-interruptible fail" href="https://bugs.freedesktop.org/show_bug.cgi?id=79052">bug 79052</a> from <a class="email" href="mailto:carlos.santa@intel.com" title="Carlos Santa <carlos.santa@intel.com>"> Carlos Santa</a> <pre>On BDW, it passed 3 times and failed twice, behavior appears to be sporadic. I've attached the dmesg of the last failure (read bottom first). This was on drm-nightly ~11/29/2016 csanta@csanta-bdw:~$ sudo ./kms_flip --run-subtest vblank-vs-suspend IGT-Version: 1.16-gcb214ef (x86_64) (Linux: 4.9.0-rc6+ x86_64) Using monotonic timestamps Beginning vblank-vs-suspend on pipe A, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:07:13 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:07:30 2016 vblank-vs-suspend on pipe A, connector HDMI-A-1: PASSED Beginning vblank-vs-suspend on pipe B, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:07:48 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:08:06 2016 vblank-vs-suspend on pipe B, connector HDMI-A-1: PASSED Beginning vblank-vs-suspend on pipe C, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:08:23 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:08:41 2016 vblank-vs-suspend on pipe C, connector HDMI-A-1: PASSED Subtest vblank-vs-suspend: SUCCESS (10.980s) csanta@csanta-bdw:~$ sudo ./kms_flip --run-subtest vblank-vs-suspend IGT-Version: 1.16-gcb214ef (x86_64) (Linux: 4.9.0-rc6+ x86_64) Using monotonic timestamps Beginning vblank-vs-suspend on pipe A, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:09:29 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:09:46 2016 vblank-vs-suspend on pipe A, connector HDMI-A-1: PASSED Beginning vblank-vs-suspend on pipe B, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:10:04 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:10:22 2016 vblank-vs-suspend on pipe B, connector HDMI-A-1: PASSED Beginning vblank-vs-suspend on pipe C, connector HDMI-A-1 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:11:12 2016 .rtcwake: assuming RTC uses UTC ... rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Nov 30 02:11:29 2016 (kms_flip:1764) CRITICAL: Test assertion failure function check_state, file kms_flip.c:510: (kms_flip:1764) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150 (kms_flip:1764) CRITICAL: unexpected vblank seq 2309, should be < 487 Stack trace: #0 [__igt_fail_assert+0x101] #1 [check_state+0x331] #2 [<unknown>+0x331] Subtest vblank-vs-suspend failed. **** DEBUG **** (kms_flip:1764) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd) (kms_flip:1764) DEBUG: Test requirement passed: modes (kms_flip:1764) INFO: Beginning vblank-vs-suspend on pipe A, connector HDMI-A-1 (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680) (kms_flip:1764) igt-kms-INFO: 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 177.945129 current_received_ts = 178.617435 current_seq = 9699 count = 0 seq_step = 10 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 177.945129 last_received_ts = 178.617435 last_seq = 9699 current_ts = 178.783608 current_received_ts = 181.087330 current_seq = 9734 count = 1 seq_step = 10 (kms_flip:1764) DEBUG: testing ts continuity: Current frame 9734, old frame 9699 (kms_flip:1764) INFO: vblank-vs-suspend on pipe A, connector HDMI-A-1: PASSED (kms_flip:1764) INFO: Beginning vblank-vs-suspend on pipe B, connector HDMI-A-1 (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680) (kms_flip:1764) igt-kms-INFO: 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 181.332199 current_received_ts = 183.546924 current_seq = 416 count = 0 seq_step = 10 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 181.332199 last_received_ts = 183.546924 last_seq = 416 current_ts = 183.707285 current_received_ts = 186.016916 current_seq = 543 count = 1 seq_step = 10 (kms_flip:1764) DEBUG: testing ts continuity: Current frame 543, old frame 416 (kms_flip:1764) INFO: vblank-vs-suspend on pipe B, connector HDMI-A-1: PASSED (kms_flip:1764) INFO: Beginning vblank-vs-suspend on pipe C, connector HDMI-A-1 (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0) (kms_flip:1764) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680) (kms_flip:1764) igt-kms-INFO: 1920x1080 60 1920 1968 2000 2080 1080 1083 1088 1112 0x9 0x48 138780 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 0.000000 last_received_ts = 0.000000 last_seq = 0 current_ts = 186.244780 current_received_ts = 219.213699 current_seq = 337 count = 0 seq_step = 10 (kms_flip:1764) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state) (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0 (kms_flip:1764) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0 (kms_flip:1764) DEBUG: name = vblank last_ts = 186.244780 last_received_ts = 219.213699 last_seq = 337 current_ts = 219.377694 current_received_ts = 220.648964 current_seq = 2309 count = 1 seq_step = 10 (kms_flip:1764) CRITICAL: Test assertion failure function check_state, file kms_flip.c:510: (kms_flip:1764) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150 (kms_flip:1764) CRITICAL: unexpected vblank seq 2309, should be < 487 **** END **** Subtest vblank-vs-suspend: FAIL (42.825s)</pre> </div> <hr> You are receiving this mail because: <ul> <li>You are on the CC list for the bug.</li> <li>You are the QA Contact for the bug.</li> <li>You are the assignee for the bug.</li> </ul> </body> </html>