[Bug 102662] [IGT] kms_flip at 2x-vblank-vs-suspend(-interruptible) fail with unexpected vblank seq

Wed Feb 14 21:36:00 UTC 2018

https://bugs.freedesktop.org/show_bug.cgi?id=102662

--- Comment #9 from Elizabeth <elizabethx.de.la.torre.mena at intel.com> ---
This tests keep showing the same issue with the igt all in the past weeks.

igt at kms_flip@2x-vblank-vs-suspend

Out     
IGT-Version: 1.21-g37bd27f (x86_64) (Linux:
4.15.0-drm-intel-qa-ww6-commit-6c10ba2+ x86_64)
Using monotonic timestamps
Beginning 2x-vblank-vs-suspend on pipe A:B, connector eDP-1:DP-1
  1920x1080 60 1920 1936 1952 2104 1080 1083 1097 1116 0xa 0x48 141000
  1920x1080 60 1920 2008 2052 2200 1080 1084 1089 1125 0x5 0x48 148500
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon May 23 08:26:23 2016
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon May 23 08:26:41 2016
Stack trace:
  #0 [__igt_fail_assert+0x101]
  #1 [check_state+0x352]
  #2 [<unknown>+0x352]
Subtest 2x-vblank-vs-suspend: FAIL (9.166s)
Err     
(kms_flip:4880) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:508:
(kms_flip:4880) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:4880) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:4880) CRITICAL: unexpected vblank seq 4579, should be < 4520
Subtest 2x-vblank-vs-suspend failed.
**** DEBUG ****
(kms_flip:4880) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 ||
!is_wedged(drm_fd)
(kms_flip:4880) DEBUG: Test requirement passed: modes
(kms_flip:4880) INFO: Beginning 2x-vblank-vs-suspend on pipe A:B, connector
eDP-1:DP-1
(kms_flip:4880) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:4880) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:4880) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:4880) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:4880) igt-kms-INFO:   1920x1080 60 1920 1936 1952 2104 1080 1083 1097
1116 0xa 0x48 141000
(kms_flip:4880) igt-kms-INFO:   1920x1080 60 1920 2008 2052 2200 1080 1084 1089
1125 0x5 0x48 148500
(kms_flip:4880) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:4880) igt-core-INFO: [cmd] rtcwake: wakeup from "mem" using /dev/rtc0
at Mon May 23 08:26:23 2016
(kms_flip:4880) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 154.291279
current_received_ts = 158.426682
current_seq = 4370
count = 0
seq_step = 10
(kms_flip:4880) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:4880) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:4880) igt-core-INFO: [cmd] rtcwake: wakeup from "mem" using /dev/rtc0
at Mon May 23 08:26:41 2016
(kms_flip:4880) DEBUG: name = vblank
last_ts = 154.291279
last_received_ts = 158.426682
last_seq = 4370
current_ts = 158.589866
current_received_ts = 162.083135
current_seq = 4579
count = 1
seq_step = 10
(kms_flip:4880) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:508:
(kms_flip:4880) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:4880) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:4880) CRITICAL: unexpected vblank seq 4579, should be < 4520
(kms_flip:4880) igt-core-INFO: Stack trace:
(kms_flip:4880) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:4880) igt-core-INFO:   #1 [check_state+0x352]
(kms_flip:4880) igt-core-INFO:   #2 [<unknown>+0x352]
****  END  ****

igt at kms_flip@2x-vblank-vs-suspend-interruptible
Out     
IGT-Version: 1.21-g37bd27f (x86_64) (Linux:
4.15.0-drm-intel-qa-ww6-commit-6c10ba2+ x86_64)
Using monotonic timestamps
Beginning 2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:DP-1
  1920x1080 60 1920 1936 1952 2104 1080 1083 1097 1116 0xa 0x48 141000
  1920x1080 60 1920 2008 2052 2200 1080 1084 1089 1125 0x5 0x48 148500
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon May 23 10:14:40 2016
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon May 23 10:14:58 2016
Stack trace:
  #0 [__igt_fail_assert+0x101]
  #1 [check_state+0x352]
  #2 [<unknown>+0x352]
Subtest 2x-vblank-vs-suspend-interruptible: FAIL (8.477s)
Err     
(kms_flip:4227) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:508:
(kms_flip:4227) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:4227) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:4227) CRITICAL: unexpected vblank seq 40618, should be < 40601
Subtest 2x-vblank-vs-suspend-interruptible failed.
**** DEBUG ****
(kms_flip:4227) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 ||
!is_wedged(drm_fd)
(kms_flip:4227) DEBUG: Test requirement passed: modes
(kms_flip:4227) INFO: Beginning 2x-vblank-vs-suspend-interruptible on pipe A:B,
connector eDP-1:DP-1
(kms_flip:4227) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:4227) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:4227) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920,
height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:4227) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:4227) igt-kms-INFO:   1920x1080 60 1920 1936 1952 2104 1080 1083 1097
1116 0xa 0x48 141000
(kms_flip:4227) igt-kms-INFO:   1920x1080 60 1920 2008 2052 2200 1080 1084 1089
1125 0x5 0x48 148500
(kms_flip:4227) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:4227) igt-core-INFO: [cmd] rtcwake: wakeup from "mem" using /dev/rtc0
at Mon May 23 10:14:40 2016
(kms_flip:4227) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 820.980824
current_received_ts = 824.397807
current_seq = 40451
count = 0
seq_step = 10
(kms_flip:4227) igt-core-DEBUG: Test requirement passed:
!igt_run_in_simulation()
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: (power_dir =
open("/sys/power", O_RDONLY)) >= 0
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed:
get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: test ==
SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:4227) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:4227) igt-core-INFO: [cmd] rtcwake: wakeup from "mem" using /dev/rtc0
at Mon May 23 10:14:58 2016
(kms_flip:4227) DEBUG: name = vblank
last_ts = 820.980824
last_received_ts = 824.397807
last_seq = 40451
current_ts = 824.557827
current_received_ts = 828.101958
current_seq = 40618
count = 1
seq_step = 10
(kms_flip:4227) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:508:
(kms_flip:4227) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:4227) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:4227) CRITICAL: unexpected vblank seq 40618, should be < 40601
(kms_flip:4227) igt-core-INFO: Stack trace:
(kms_flip:4227) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:4227) igt-core-INFO:   #1 [check_state+0x352]
(kms_flip:4227) igt-core-INFO:   #2 [<unknown>+0x352]
****  END  ****

Note that this one was timed out:
igt at kms_flip@vblank-vs-suspend-interruptible
Out     
An internal exception that should have been handled was not:
Test run time exceeded timeout value (600 seconds)
Err     
(kms_flip:5664) CRITICAL: Test assertion failure function check_state, file
kms_flip.c:508:
(kms_flip:5664) CRITICAL: Failed assertion: es->current_seq - es->last_seq <=
150
(kms_flip:5664) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:5664) CRITICAL: unexpected vblank seq 4715, should be < 4696
Received signal SIGBUS.
Stack trace:

-- 
You are receiving this mail because:
You are the assignee for the bug.
You are on the CC list for the bug.
You are the QA Contact for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20180214/2d9fe5c0/attachment.html>