<html>
    <head>
      <base href="https://bugs.freedesktop.org/">
    </head>
    <body>
      <p>
        <div>
            <b><a class="bz_bug_link 
          bz_status_NEW "
   title="NEW - [BDW] kms_flip / vblank-vs-suspend-interruptible is failing"
   href="https://bugs.freedesktop.org/show_bug.cgi?id=98289#c6">Comment # 6</a>
              on <a class="bz_bug_link 
          bz_status_NEW "
   title="NEW - [BDW] kms_flip / vblank-vs-suspend-interruptible is failing"
   href="https://bugs.freedesktop.org/show_bug.cgi?id=98289">bug 98289</a>
              from <span class="vcard"><a class="email" href="mailto:carlos.santa@intel.com" title="Carlos Santa <carlos.santa@intel.com>"> <span class="fn">Carlos Santa</span></a>
</span></b>
        <pre>I am seeing this when it fails:

csanta@csanta-bdw:~$ sudo ./kms_flip --r vblank-vs-suspend-interruptible
[sudo] password for csanta: 
IGT-Version: 1.16-gcb214ef (x86_64) (Linux: 4.9.0-rc6+ x86_64)
Using monotonic timestamps
Beginning vblank-vs-suspend-interruptible 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 Thu Dec  1 01:01:31 2016
.rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Thu Dec  1 01:01:49 2016

vblank-vs-suspend-interruptible on pipe A, connector HDMI-A-1: PASSED

Beginning vblank-vs-suspend-interruptible 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 Thu Dec  1 01:02:07 2016
.rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Thu Dec  1 01:02:25 2016

vblank-vs-suspend-interruptible on pipe B, connector HDMI-A-1: PASSED

Beginning vblank-vs-suspend-interruptible 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 Thu Dec  1 01:03:16 2016
.rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Thu Dec  1 01:03:33 2016
(kms_flip:1128) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:510:
(kms_flip:1128) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:1128) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1128) CRITICAL: unexpected vblank seq 2022, should be < 164
Stack trace:
  #0 [__igt_fail_assert+0x101]
  #1 [check_state+0x331]
  #2 [<unknown>+0x331]
Subtest vblank-vs-suspend-interruptible failed.
**** DEBUG ****
(kms_flip:1128) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 ||
!is_wedged(drm_fd)
(kms_flip:1128) DEBUG: Test requirement passed: modes
(kms_flip:1128) INFO: Beginning vblank-vs-suspend-interruptible on pipe A,
connector HDMI-A-1
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:1128) igt-kms-INFO:   1920x1080 60 1920 1968 2000 2080 1080 1083 1088
1112 0x9 0x48 138780
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 73.711808
current_received_ts = 75.530334
current_seq = 4357
count = 0
seq_step = 10
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 73.711808
last_received_ts = 75.530334
last_seq = 4357
current_ts = 75.682263
current_received_ts = 78.011187
current_seq = 4461
count = 1
seq_step = 10
(kms_flip:1128) DEBUG: testing ts continuity: Current frame 4461, old frame
4357
(kms_flip:1128) INFO: 
vblank-vs-suspend-interruptible on pipe A, connector HDMI-A-1: PASSED

(kms_flip:1128) INFO: Beginning vblank-vs-suspend-interruptible on pipe B,
connector HDMI-A-1
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:1128) igt-kms-INFO:   1920x1080 60 1920 1968 2000 2080 1080 1083 1088
1112 0x9 0x48 138780
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 78.252519
current_received_ts = 80.494014
current_seq = 14
count = 0
seq_step = 10
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 78.252519
last_received_ts = 80.494014
last_seq = 14
current_ts = 80.660646
current_received_ts = 82.974470
current_seq = 142
count = 1
seq_step = 10
(kms_flip:1128) DEBUG: testing ts continuity: Current frame 142, old frame 14
(kms_flip:1128) INFO: 
vblank-vs-suspend-interruptible on pipe B, connector HDMI-A-1: PASSED

(kms_flip:1128) INFO: Beginning vblank-vs-suspend-interruptible on pipe C,
connector HDMI-A-1
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1128) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:1128) igt-kms-INFO:   1920x1080 60 1920 1968 2000 2080 1080 1083 1088
1112 0x9 0x48 138780
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 83.203833
current_received_ts = 116.777497
current_seq = 14
count = 0
seq_step = 10
(kms_flip:1128) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1128) igt-aux-DEBUG: Test requirement passed: system(cmd) == 0
(kms_flip:1128) DEBUG: name = vblank
last_ts = 83.203833
last_received_ts = 116.777497
last_seq = 14
current_ts = 116.935350
current_received_ts = 118.224287
current_seq = 2022
count = 1
seq_step = 10
(kms_flip:1128) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:510:
(kms_flip:1128) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:1128) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1128) CRITICAL: unexpected vblank seq 2022, should be < 164
****  END  ****
Subtest vblank-vs-suspend-interruptible: FAIL (44.647s)</pre>
        </div>
      </p>


      <hr>
      <span>You are receiving this mail because:</span>

      <ul>
          <li>You are the QA Contact for the bug.</li>
          <li>You are on the CC list for the bug.</li>
      </ul>
    </body>
</html>