Bug 102662

Summary:

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

Product:

DRI

Reporter:

Elizabeth <elizabethx.de.la.torre.mena>

Component:

DRM/Intel

Assignee:

Maarten Lankhorst <bugs>

Status:

CLOSED FIXED

QA Contact:

Intel GFX Bugs mailing list <intel-gfx-bugs>

Severity:

normal

Priority:

medium

CC:

bugs, hector.franciscox.velazquez.suriano, intel-gfx-bugs

Version:

XOrg git

Hardware:

Other

OS:

All

Whiteboard:

ReadyForDev

i915 platform:

GLK

i915 features:

display/Other

Attachments:

Description	Flags
Test_outputs	none
dmesg log	none

Description Elizabeth 2017-09-11 19:59:17 UTC

Created attachment 134167 [details]
Test_outputs

On GLK these tests keep failing:
igt@kms_flip@2x-vblank-vs-suspend
igt@kms_flip@2x-vblank-vs-suspend-interruptible

Assertion: 
(kms_flip:1569) CRITICAL: Test assertion failure function check_state, file kms_flip.c:508:
(kms_flip:1569) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150
(kms_flip:1569) CRITICAL: Last errno: 4, Interrupted system call
(kms_flip:1569) CRITICAL: unexpected vblank seq 19067, should be < 19059
(kms_flip:1569) igt-core-INFO: Stack trace:
(kms_flip:1569) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:1569) igt-core-INFO:   #1 [check_state+0x331]
(kms_flip:1569) igt-core-INFO:   #2 [<unknown>+0x331]

With the following configuration:
======================================
             Software
======================================
kernel version              : 4.13.0-drm-tip-ww37-commit-14ea1a9+
hostname                    : GLK-1-GLKRVP1DDR405
architecture                : x86_64
os version                  : Ubuntu 16.10
os codename                 : yakkety
kernel driver               : i915
bios revision               : 60.34
bios release date           : 08/09/2017
ksc                         : 1.41
hardware acceleration       : disabled
swap partition              : enabled on (/dev/sda3)

======================================
        Graphic drivers
======================================
grep: /opt/X11R7/var/log/Xorg.0.log: No such file or directory
libdrm                      : 2.4.70
cairo                       : 1.14.6

======================================
             Hardware
======================================
platform                   : Geminilake
motherboard model          : Geminilake
motherboard id             : GLKRVP1DDR4(05)
form factor                : Hand Held
manufacturer               : IntelCorp.
cpu family                 : Other
cpu family id              : 6
cpu information            : Genuine Intel(R) CPU @ 1.10GHz
gpu card                   : Intel Corporation Device 3185 (rev 03) (prog-if 00 [VGA controller])
memory ram                 : 7.64 GB
max memory ram             : 16 GB
cpu thread                 : 2
cpu core                   : 2
cpu model                  : 122
cpu stepping               : 1
socket                     : Other
hard drive                 : 223GiB (240GB)
current cd clock frequency : 316800 kHz
maximum cd clock frequency : 316800 kHz
displays connected         : eDP-1 DP-1 HDMI-A-2

======================================
             Firmware
======================================
dmc fw loaded             : yes
dmc version               : 1.4
guc fw loaded             : SUCCESS
guc version wanted        : 10.56
guc version found         : 10.56

======================================
             kernel parameters
======================================
quiet drm.debug=0x1e i915.enable_guc_loading=2 i915.enable_guc_submission=2 i915.alpha_support=1 auto panic=1 nmi_watchdog=panic intel_iommu=igfx_off resume=/dev/sda3 fastboot

Comment 1 Elizabeth 2017-09-11 20:04:35 UTC

Created attachment 134168 [details]
dmesg log

Comment 2 Hector Velazquez 2017-09-28 12:34:25 UTC

This test is still failing on GLK QA

Tests List:

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


====================================================
Output
====================================================
. . .
**** DEBUG ****
(kms_flip:1853) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd)
(kms_flip:1853) DEBUG: Test requirement passed: modes
(kms_flip:1853) INFO: Beginning 2x-vblank-vs-suspend on pipe A:B, connector eDP-1:DP-1
(kms_flip:1853) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1853) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=7680)
(kms_flip:1853) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x0, size=0)
(kms_flip:1853) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=7680)
(kms_flip:1853) igt-kms-INFO:   1920x1080 60 1920 1936 1952 2104 1080 1083 1097 1116 0xa 0x48 141000
(kms_flip:1853) igt-kms-INFO:   1920x1080 60 1920 2008 2052 2200 1080 1084 1089 1125 0x5 0x48 148500
(kms_flip:1853) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:1853) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 215.510677
current_received_ts = 220.012503
current_seq = 10908
count = 0
seq_step = 10
(kms_flip:1853) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:1853) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:1853) DEBUG: name = vblank
last_ts = 215.510677
last_received_ts = 220.012503
last_seq = 10908
current_ts = 220.171654
current_received_ts = 223.869214
current_seq = 11144
count = 1
seq_step = 10
(kms_flip:1853) CRITICAL: Test assertion failure function check_state, file kms_flip.c:508:
(kms_flip:1853) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150
(kms_flip:1853) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:1853) CRITICAL: unexpected vblank seq 11144, should be < 11058
(kms_flip:1853) igt-core-INFO: Stack trace:
(kms_flip:1853) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:1853) igt-core-INFO:   #1 [check_state+0x331]
(kms_flip:1853) igt-core-INFO:   #2 [<unknown>+0x331]
****  END  ****
. . .


This is my configuration:

======================================
        Graphic stack
======================================
Component: drm
    tag: libdrm-2.4.81-56-g7c71188
    commit: 7c71188610b4ceba0339c2bc884320bcb749adee

Component: cairo
    tag: 1.15.6-42-gdccbed7
    commit: dccbed7d78d32bd3b912e8810379451dd94e6a1f

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.19-332-g0a91a5e
    commit: 0a91a5e9624d41d23b79e2540eda111cb56d42d9

Component: piglit
    tag: piglit-v1
    commit: 95e2f51a28b6cf7ff77d84e1234121c98f10ef64
	
======================================
             Software
======================================
kernel version              : 4.14.0-rc2-drm-tip-ww39-commit-d76cbbc+
hostname                    : GLK-2-GLKRVP1DDR405
architecture                : x86_64
os version                  : Ubuntu 16.10
os codename                 : yakkety
kernel driver               : i915
bios revision               : 62.30
bios release date           : 08/22/2017
ksc                         : 1.41
hardware acceleration       : disabled
swap partition              : enabled on (/dev/sda3)

======================================
        Graphic drivers
======================================
grep: /opt/X11R7/var/log/Xorg.0.log: No such file or directory
libdrm                      : 2.4.83
cairo                       : 1.15.9
intel-gpu-tools (tag)       : intel-gpu-tools-1.19-332-g0a91a5e
intel-gpu-tools (commit)    : 0a91a5e

======================================
             Hardware
======================================
. . .

======================================
             Firmware
======================================
dmc fw loaded             : yes
dmc version               : 1.4
guc fw loaded             : SUCCESS
guc version wanted        : 10.56
guc version found         : 10.56
huc fw loaded             : yes

======================================
             kernel parameters
======================================
quiet drm.debug=0xe pci=pcie_bus_safe i915.alpha_support=1 i915.enable_guc_loading=2 i915.enable_guc_submission=2 intel_iommu=igfx_off auto panic=1 nmi_watchdog=panic resume=/dev/sda3 fastboot

Comment 3 Hector Velazquez 2017-10-03 15:24:26 UTC

This tests still failing on GLK QA

Tests List:

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


====================================================
Output 
====================================================
. . .


This is my configuration:

======================================
        Graphic stack
======================================
Component: drm
    tag: libdrm-2.4.81-57-g1dd84e0
    commit: 1dd84e01a972b1759839a7326009be24ab3e6de2

Component: cairo
    tag: 1.15.6-42-gdccbed7
    commit: dccbed7d78d32bd3b912e8810379451dd94e6a1f

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.19-357-g1e99f8b
    commit: 1e99f8b8d2563d7f5c4e82932bab15abc5eacaef

Component: piglit
    tag: piglit-v1
    commit: 5aa6eea37f44f818632a3dad4c1a7478085bd56d

	
======================================
	     Software
======================================
kernel version              : 4.14.0-rc3-drm-tip-ww40-commit-2f14e31+
hostname                    : GLK-1-GLKRVP1DDR405
architecture                : x86_64
os version                  : Ubuntu 16.10
os codename                 : yakkety
kernel driver               : i915
bios revision               : 62.30
bios release date           : 08/22/2017
ksc                         : 1.41
hardware acceleration       : disabled
swap partition              : enabled on (/dev/sda3)

======================================
	Graphic drivers
======================================
libdrm                      : 2.4.83
cairo                       : 1.15.9
intel-gpu-tools (tag)       : intel-gpu-tools-1.19-357-g1e99f8b
intel-gpu-tools (commit)    : 1e99f8b

======================================
	     Hardware
======================================
. . .

======================================
	     Firmware
======================================
dmc fw loaded             : yes
dmc version               : 1.4
guc fw loaded             : SUCCESS
guc version wanted        : 10.56
guc version found         : 10.56

======================================
	     kernel parameters
======================================
quiet drm.debug=0x1e pci=pcie_bus_safe intel_iommu=igfx_off auto panic=1 nmi_watchdog=panic i915.alpha_support=1 i915.enable_guc_loading=2 i915.enable_guc_submission=2 resume=/dev/sda3 fastboot

Comment 4 Luis Botello 2017-10-24 07:29:54 UTC

Tests are still failing on GLK with the latest drm-tip kernel:

/home/shared/kernels_mx/drm-intel-qa/WW43/4.14.0-rc5-d2ec28c
commit d2ec28c53833297976d0754e0e82c3d7490b149c
Author: Jani Nikula 
AuthorDate: Mon Oct 23 11:56:01 2017 +0300
Commit: Jani Nikula 
CommitDate: Mon Oct 23 11:56:01 2017 +0300

drm-tip: 2017y-10m-23d-08h-55m-00s UTC integration manifest

Comment 5 Luis Botello 2017-10-24 18:15:54 UTC

Issue is not related with DMC or GuC firmwares.

Comment 6 Hector Velazquez 2017-11-17 21:26:15 UTC

This test still failing on GLK QA

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


IGT-Version: 1.20-g88d6550 (x86_64) (Linux: 4.14.0-drm-tip-ww46-commit-1fc4fe8+ x86_64)

Component: drm
    tag: libdrm-2.4.81-107-g18ffe48
    commit: 18ffe485cdfa41d48b6f2d3080cb990d28c27d57

Component: cairo
    tag: 1.15.6-83-g0c8070f
    commit: 0c8070f5bc74c124e6393b433a61807a8e4bee5d

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.19-483-g88d6550
    commit: 88d6550795fad3974d77e4db2f563c5e2e8872e1

Component: piglit
    tag: piglit-v1
    commit: b6aee208234287380d2e55c17dc2d236931284fa

Comment 7 Elizabeth 2017-12-20 16:20:46 UTC

*** Bug 104350 has been marked as a duplicate of this bug. ***

Comment 8 Hector Velazquez 2017-12-20 16:24:21 UTC

This tests continue failing on CNL QA...

kms_flip@2x-vblank-vs-suspend-interruptible

====================================================
output sample
====================================================

**** DEBUG ****
(kms_flip:2528) DEBUG: Test requirement passed: (flags & TEST_HANG) == 0 || !is_wedged(drm_fd)
(kms_flip:2528) DEBUG: Test requirement passed: modes
(kms_flip:2528) INFO: Beginning 2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=10240)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=10240)
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:11 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 2839.228791
current_received_ts = 2842.441589
current_seq = 79547
count = 0
seq_step = 10
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:30 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 2839.228791
last_received_ts = 2842.441589
last_seq = 79547
current_ts = 2842.593600
current_received_ts = 2846.109543
current_seq = 79627
count = 1
seq_step = 10
(kms_flip:2528) DEBUG: testing ts continuity: Current frame 79627, old frame 79547
(kms_flip:2528) INFO: 
2x-vblank-vs-suspend-interruptible on pipe A:B, connector eDP-1:HDMI-A-1: PASSED

(kms_flip:2528) INFO: Beginning 2x-vblank-vs-suspend-interruptible on pipe A:C, connector eDP-1:HDMI-A-1
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=10240)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2560, height=1440, format=0x34325258, tiling=0x0, size=0)
(kms_flip:2528) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=10240)
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-kms-INFO:   2560x1440 60 2560 2608 2640 2720 1440 1443 1448 1481 0xa 0x48 241750
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:07:51 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 0.000000
last_received_ts = 0.000000
last_seq = 0
current_ts = 2847.666128
current_received_ts = 2852.181685
current_seq = 79744
count = 0
seq_step = 10
(kms_flip:2528) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_flip:2528) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_flip:2528) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Tue Dec 19 15:08:12 2017
(kms_flip:2528) DEBUG: name = vblank
last_ts = 2847.666128
last_received_ts = 2852.181685
last_seq = 79744
current_ts = 2852.344429
current_received_ts = 2856.890145
current_seq = 79902
count = 1
seq_step = 10
(kms_flip:2528) CRITICAL: Test assertion failure function check_state, file kms_flip.c:508:
(kms_flip:2528) CRITICAL: Failed assertion: es->current_seq - es->last_seq <= 150
(kms_flip:2528) CRITICAL: Last errno: 25, Inappropriate ioctl for device
(kms_flip:2528) CRITICAL: unexpected vblank seq 79902, should be < 79894
(kms_flip:2528) igt-core-INFO: Stack trace:
(kms_flip:2528) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_flip:2528) igt-core-INFO:   #1 [check_state+0x352]
(kms_flip:2528) igt-core-INFO:   #2 [<unknown>+0x352]
****  END  ****


This is my configuration:

======================================
	     Software
======================================
kernel version              : 4.15.0-rc4-drm-tip-ww51-commit-cd7e144+
hostname                    : gfx-desktop
architecture                : x86_64
os version                  : Ubuntu 17.04
os codename                 : zesty
kernel driver               : i915
bios revision               : 114.4
bios release date           : 11/30/2017
ksc                         : 1.33
hardware acceleration       : enabled
swap partition              : enabled on (/dev/nvme0n1p2)

======================================
	Graphic drivers
======================================
mesa                        : 17.4.0-devel (git-8d00e63ca8
modesetting                 : enabled
modesetting compiled for    : 1.19.99.1 X.Org Video Driver
libdrm                      : 2.4.89
cairo                       : 1.15.11
intel-gpu-tools (tag)       : intel-gpu-tools-1.20-232-gc0be3310
intel-gpu-tools (commit)    : c0be3310

======================================
	     Hardware
======================================
motherboard model          : CannonLakeClientPlatform
motherboard id             : CannonLakeYLPDDR4RVP
form factor                : Laptop
manufacturer               : IntelCorporation
cpu family                 : Other
cpu family id              : 6
cpu information            : Genuine Intel(R) CPU 0000 @ 1.00GHz
gpu card                   : Intel Corporation Device 5a49 (rev 03) (prog-if 00 [VGA controller])
memory ram                 : 7.61 GB
max memory ram             : 8 GB
display resolution         : 4480x1440
cpu thread                 : 4
cpu core                   : 2
cpu model                  : 102
cpu stepping               : 2
socket                     : Other
current cd clock frequency : 336000 kHz
maximum cd clock frequency : 528000 kHz
displays connected         : eDP-1 HDMI-A-1

======================================
	     Firmware
======================================
dmc fw loaded             : yes
dmc version               : 1.6
guc fw loaded             : fetch NONE, load NONE
guc version wanted        : wanted 0.0, found 0.0
guc version found         : wanted 0.0, found 0.0

======================================
	     kernel parameters
======================================
quiet splash drm.debug=0xe i915.alpha_support=1 resume=/dev/nvme0n1p2

Comment 9 Elizabeth 2018-02-14 21:36:00 UTC

This tests keep showing the same issue with the igt all in the past weeks.

igt@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@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@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:

Comment 10 Hector Velazquez 2018-02-28 22:20:50 UTC

This tests has pass successfully on CNL QA 
Tests List:
igt@kms_flip@2x-vblank-vs-suspend

This tests has skipped on CNL QA 
Tests List:
igt@kms_flip@2x-vblank-vs-suspend-interruptible

This tests continue failing on GLK QA 
Tests List:
igt@kms_flip@2x-vblank-vs-suspend
igt@kms_flip@2x-vblank-vs-suspend-interruptible

output sample:
(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.
. . .
(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.
. . .

software:
IGT-Version: 1.21-g37bd27f (x86_64) (Linux: 4.15.0-drm-intel-qa-ww6-commit-6c10ba2+ x86_64)

(removing CNL from the platform list, verified as fixed on CNL)

Comment 11 Jani Saarinen 2018-03-29 07:10:07 UTC

First of all. Sorry about spam.
This is mass update for our bugs. 

Sorry if you feel this annoying but with this trying to understand if bug still valid or not.
If bug investigation still in progress, please ignore this and I apologize!

If you think this is not anymore valid, please comment to the bug that can be closed.
If you haven't tested with our latest pre-upstream tree(drm-tip), can you do that also to see if issue is valid there still and if you cannot see issue there, please comment to the bug.

Comment 12 Elizabeth 2018-04-10 16:00:50 UTC

Can't found this tests in the new gpu tools packages. CI also stopped running these ones since CI_DRM_3680, but I can't found information of these being removed. If the tests don't exist anymore, this bug should be closed as invalid, someone who can confirm this? Thank you.

Comment 13 Jani Saarinen 2018-04-10 19:13:58 UTC

Maarten, can you confirm?

Comment 14 Maarten Lankhorst 2018-04-11 09:01:06 UTC

Oh right, I removed those tests as they became redundant with kms_vblank. No wonder the failures are gone, the tests definitely are. :)

Comment 15 Jani Saarinen 2018-04-11 09:55:45 UTC

Lets close then ;)

Comment 16 Jani Saarinen 2018-04-11 10:58:43 UTC

Document series that removed tests: https://patchwork.freedesktop.org/series/36006/

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.