104021 – [IGT] kms_fbcon_fbt subtest psr-suspend, failed with assertion: ret == 0 / rtcwake failed with 1

Bug 104021 - [IGT] kms_fbcon_fbt subtest psr-suspend, failed with assertion: ret == 0 / rtcwake failed with 1

Summary: [IGT] kms_fbcon_fbt subtest psr-suspend, failed with assertion: ret == 0 / rt...

Status:	CLOSED FIXED

Alias:	None

Product:	DRI
Classification:	Unclassified
Component:	DRM/Intel (show other bugs)
Version:	DRI git
Hardware:	x86-64 (AMD64) Linux (All)

Importance:	medium normal
Assignee:	Dhinakaran Pandiyan
QA Contact:	Intel GFX Bugs mailing list

URL:
Whiteboard:	ReadyForDev
Keywords:

Depends on:
Blocks:

Reported:	2017-12-01 18:30 UTC by Hector Velazquez
Modified:	2018-08-04 09:28 UTC (History)
CC List:	1 user (show)

See Also:
i915 platform:	CFL, IVB
i915 features:	display/atomic

Attachments
otuput (332.02 KB, text/plain) 2017-12-01 18:30 UTC, Hector Velazquez	no flags	Details
dmesg -w (2.60 MB, text/plain) 2017-12-01 18:30 UTC, Hector Velazquez	no flags	Details
kernel log (10.91 MB, text/plain) 2018-01-16 21:41 UTC, Hector Velazquez	no flags	Details
output both tests (15.34 KB, text/plain) 2018-01-16 21:42 UTC, Hector Velazquez	no flags	Details
output (IVB moved) (35.10 KB, text/plain) 2018-03-01 16:12 UTC, Hector Velazquez	no flags	Details
dmesg (IVB moved) (240.47 KB, text/plain) 2018-03-01 16:13 UTC, Hector Velazquez	no flags	Details
View All

Description Hector Velazquez 2017-12-01 18:30:21 UTC

Created attachment 135860 [details]
otuput

Comment 1 Hector Velazquez 2017-12-01 18:30:27 UTC

This tests was failing on CFL QA

igt@kms_fbcon_fbt@psr-suspend

====================================================
output sample
====================================================
**** DEBUG ****
(kms_fbcon_fbt:2458) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
(. . .)
(kms_fbcon_fbt:2458) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
(kms_fbcon_fbt:2458) DEBUG: PSR status: Sink_Support: yes
Source_OK: yes
Enabled: no
Active: no
Busy frontbuffer bits: 0x000
Re-enable work scheduled: no
Main link in standby mode: no
HW Enabled & Active bit: no

(kms_fbcon_fbt:2458) DEBUG: Test requirement passed: i < drm->res->count_connectors
(kms_fbcon_fbt:2458) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=0)
(kms_fbcon_fbt:2458) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_fbcon_fbt:2458) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_fbcon_fbt:2458) INFO: Setting 1920x1080 mode for eDP connector
(kms_fbcon_fbt:2458) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
(. . .)
(kms_fbcon_fbt:2458) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
(kms_fbcon_fbt:2458) DEBUG: PSR status: Sink_Support: yes
Source_OK: yes
Enabled: yes
Active: yes
Busy frontbuffer bits: 0x000
Re-enable work scheduled: no
Main link in standby mode: no
HW Enabled & Active bit: yes

(kms_fbcon_fbt:2458) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_fbcon_fbt:2458) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_fbcon_fbt:2458) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_fbcon_fbt:2458) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_fbcon_fbt:2458) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_fbcon_fbt:2458) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Fri Dec  1 18:07:48 2017
(kms_fbcon_fbt:2458) igt-core-WARNING: [cmd] rtcwake: write error[cmd] 
(kms_fbcon_fbt:2458) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_fbcon_fbt:2458) igt-aux-DEBUG: suspend_stats:
success: 0
fail: 1
failed_freeze: 0
failed_prepare: 0
failed_suspend: 1
failed_suspend_late: 0
failed_suspend_noirq: 0
failed_resume: 0
failed_resume_early: 0
failed_resume_noirq: 0
failures:
  last_failed_dev:	
			
  last_failed_errno:	-16
			0
  last_failed_step:	suspend
			
(kms_fbcon_fbt:2458) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:821:
(kms_fbcon_fbt:2458) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_fbcon_fbt:2458) igt-aux-CRITICAL: error: 1 != 0
(kms_fbcon_fbt:2458) igt-core-INFO: Stack trace:
(kms_fbcon_fbt:2458) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_fbcon_fbt:2458) igt-core-INFO:   #1 [igt_system_suspend_autoresume+0x52b]
(kms_fbcon_fbt:2458) igt-core-INFO:   #2 [<unknown>+0x52b]
****  END  ****

This is my configuration:
======================================
	Graphic stack
======================================
Component: drm
    tag: libdrm-2.4.88-25-g1749d56
    commit: 1749d56ea00f350a74f662bdad82bcafa19889da

Component: cairo
    tag: 1.15.8-61-g84fc0ce
    commit: 84fc0ce91d1a57d20500f710abc0e17de82c67df

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.20-169-gac6739b
    commit: ac6739bcc7ee9682b40e7b7ac9625674fe3dc95e

Component: piglit
    tag: piglit-v1
    commit: f76ed7991c8afd597a5797c0965c1d696e34f408
	
======================================
	     Software
======================================
kernel version              : 4.15.0-rc1-drm-tip-ww48-commit-4faecf8+
hostname                    : CFL-1
architecture                : x86_64
os version                  : Ubuntu 16.10
os codename                 : yakkety
kernel driver               : i915
bios revision               : 104.3
bios release date           : 09/14/2017
ksc                         : 1.5
hardware acceleration       : disabled
swap partition              : enabled on (/dev/nvme0n1p3)

======================================
	Graphic drivers
======================================
libdrm                      : 2.4.88
cairo                       : 1.15.9
intel-gpu-tools (tag)       : intel-gpu-tools-1.20-169-gac6739b
intel-gpu-tools (commit)    : ac6739b

======================================
	     Hardware
======================================
motherboard model          : CoffeeLakeClientPlatform
motherboard id             : CoffeeLakeSUDIMMRVP
form factor                : Desktop
manufacturer               : IntelCorporation
cpu family                 : Other
cpu family id              : 6
cpu information            : Genuine Intel(R) CPU 0000 @ 3.60GHz
gpu card                   : Intel Corporation Device 3e92 (prog-if 00 [VGA controller])
memory ram                 : 15.57 GB
max memory ram             : 32 GB
cpu thread                 : 12
cpu core                   : 6
cpu model                  : 158
cpu stepping               : 10
socket                     : Other
current cd clock frequency : 337500 kHz
maximum cd clock frequency : 675000 kHz
displays connected         : eDP-1 DP-1

======================================
	     Firmware
======================================
dmc fw loaded             : yes
dmc version               : 1.4
guc fw loaded             : fetch SUCCESS, load SUCCESS
guc version wanted        : wanted 9.39, found 9.39
guc version found         : wanted 9.39, found 9.39

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

Comment 2 Hector Velazquez 2017-12-01 18:30:41 UTC

Created attachment 135861 [details]
dmesg -w

Comment 3 Chris Wilson 2017-12-01 22:59:51 UTC

The cause are these:

[ 5848.795813] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out

it takes longer for the device to suspend due to various timeouts than the wakeup delay. That gets reported as an error from suspend_via_rtcwake.

Now the root cause is whatever is causing the modeset timeouts.

Comment 4 Armando Antonio 2017-12-08 16:16:22 UTC

The following test case has the same failure on CFL with latest configuration.

igt@kms_psr_sink_crc@suspend_psr_exit


Platform configuration:
Component: intel-gpu-tools
    tag: intel-gpu-tools-1.20-170-g476c4b4
    commit: 476c4b462e0453c70ee81664c0227fdddc26cbd0

Component: drm
    tag: libdrm-2.4.88-27-gbc21168
    commit: bc21168fa924d3fc4a000492e861f50a1a135b25

Dmesg summary:
[37737.891320] Setting dangerous option enable_psr - tainting kernel
[37748.059166] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[37758.299143] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[37768.538991] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[37778.779162] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[37789.019000] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:49:eDP-1] flip_done timed out
[37799.259174] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[37810.523246] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out

Comment 5 Hector Velazquez 2017-12-18 15:18:22 UTC

This tests continue failing on CFL QA

igt@kms_fbcon_fbt@psr-suspend

IGT-Version: 1.20-gc0be331 (x86_64) (Linux: 4.15.0-rc3-drm-intel-qa-ww50-commit-ad43db1+ x86_64)

(kms_fbcon_fbt:3103) igt-core-WARNING: [cmd] rtcwake: write error[cmd] 
(kms_fbcon_fbt:3103) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_fbcon_fbt:3103) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:821:
(kms_fbcon_fbt:3103) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_fbcon_fbt:3103) igt-aux-CRITICAL: error: 1 != 0
Subtest psr-suspend failed.

Comment 6 Hector Velazquez 2018-01-16 21:41:05 UTC

This test has failing on CFL QA 

Tests List:

igt@kms_frontbuffer_tracking@fbcpsr-suspend
igt@kms_frontbuffer_tracking@psr-suspend

using IGT-Version: 1.20-g84a3080 (x86_64) (Linux: 4.15.0-rc8-drm-tip-ww3-commit-37eb04c+ x86_64)

some tests has this output...
======================================
        output sample
======================================
. . .
**** DEBUG ****
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: fbc.can_test
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: psr.can_test
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: sink_crc.supported
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=1, pitch=7680)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=2, pitch=256)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=3, pitch=512)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1024, height=1024, format=0x34325258, tiling=0x100000000000001, size=4194304)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=4, pitch=4096)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=2420, height=3804, format=0x34325258, tiling=0x100000000000001, size=37044224)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=5, pitch=9728)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1200, format=0x34325258, tiling=0x100000000000001, size=9216000)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=6, pitch=7680)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325241, tiling=0x0, size=16384)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=7, pitch=256)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=64, height=64, format=0x34325258, tiling=0x100000000000001, size=32768)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=8, pitch=512)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(width=1920, height=1080, format=0x34325258, tiling=0x100000000000001, size=8294400)
(kms_frontbuffer_tracking:2566) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd)
(kms_frontbuffer_tracking:2566) igt-fb-DEBUG: igt_create_fb_with_bo_size(handle=11, pitch=7680)
(kms_frontbuffer_tracking:2566) DEBUG: Blue CRC:   pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) igt-draw-DEBUG: Test requirement passed: gem_get_tiling(fd, buf->handle, &tiling, &swizzle)
(kms_frontbuffer_tracking:2566) DEBUG: checking asserts in line 1780
(kms_frontbuffer_tracking:2566) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: !fbc_not_enough_stolen()
(kms_frontbuffer_tracking:2566) DEBUG: Test requirement passed: !fbc_stride_not_supported()
(kms_frontbuffer_tracking:2566) DEBUG: Calculated CRC: pipe:[941b2438 00000000 00000000 00000000 00000000] sink:[00000000c101]
(kms_frontbuffer_tracking:2566) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation()
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: get_supported_suspend_states(power_dir) & (1 << state)
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: Test requirement passed: ret == 0
(kms_frontbuffer_tracking:2566) igt-core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Mon Jan  5 18:57:52 1998
(kms_frontbuffer_tracking:2566) igt-core-WARNING: [cmd] rtcwake: [cmd] write error
(kms_frontbuffer_tracking:2566) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_frontbuffer_tracking:2566) igt-aux-DEBUG: suspend_stats:
success: 2
fail: 1
failed_freeze: 0
failed_prepare: 0
failed_suspend: 1
failed_suspend_late: 0
failed_suspend_noirq: 0
failed_resume: 0
failed_resume_early: 0
failed_resume_noirq: 0
failures:
  last_failed_dev:	
			
  last_failed_errno:	-16
			0
  last_failed_step:	suspend
			
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:821:
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_frontbuffer_tracking:2566) igt-aux-CRITICAL: error: 1 != 0
(kms_frontbuffer_tracking:2566) igt-core-INFO: Stack trace:
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #0 [__igt_fail_assert+0x101]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #1 [igt_system_suspend_autoresume+0x544]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #2 [<unknown>+0x544]
(kms_frontbuffer_tracking:2566) igt-core-INFO:   #3 [<unknown>+0x544]
****  END  ****
. . .

The tests has this dmesg-fail:

======================================
        dmesg-fail sample
======================================
. . .
[ 9136.359801] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9146.599819] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9156.839822] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9167.079863] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9167.282372] Setting dangerous option enable_fbc - tainting kernel
[ 9167.282441] Setting dangerous option enable_psr - tainting kernel
[ 9177.319791] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9187.559827] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9197.799828] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9208.039811] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9218.279818] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9228.519829] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9239.783867] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9250.023816] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9260.263827] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9270.503832] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9270.771763] Setting dangerous option enable_fbc - tainting kernel
[ 9270.771894] Setting dangerous option enable_psr - tainting kernel
[ 9281.767828] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9287.311344] Suspending console(s) (use no_console_suspend to debug)
[ 9287.651775] sd 4:0:0:0: [sda] Synchronizing SCSI cache
[ 9287.651944] sd 4:0:0:0: [sda] Stopping disk
[ 9297.895821] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9308.135914] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9318.375809] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9318.637293] PM: Some devices failed to suspend, or early wake event detected
[ 9318.645910] sd 4:0:0:0: [sda] Starting disk
[ 9318.879799] atkbd serio0: Failed to deactivate keyboard on isa0060/serio0
[ 9319.255799] atkbd serio0: Failed to enable keyboard on isa0060/serio0
[ 9324.055796] ata5: link is slow to respond, please be patient (ready=0)
[ 9328.675808] ata5: COMRESET failed (errno=-16)
[ 9329.639754] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9339.879847] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9350.119828] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9360.359815] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9360.360452] Setting dangerous option enable_psr - tainting kernel
[ 9360.360501] Setting dangerous option enable_fbc - tainting kernel
[ 9370.599832] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
[ 9380.839823] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:59:eDP-1] flip_done timed out
[ 9391.079848] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:plane 1A] flip_done timed out
[ 9401.319864] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:37:pipe A] flip_done timed out
. . .

======================================
        Graphic stack
======================================
Component: drm
    tag: libdrm-2.4.89-13-g57d3d4c
    commit: 57d3d4c96887cde016fe1d2766196accf77ca423

Component: cairo
    tag: 1.15.8-76-g6b05938
    commit: b7f313a8d2a3049e77b3497dd6040fcfab3b3c9b

Component: intel-gpu-tools
    tag: intel-gpu-tools-1.20-281-g84a3080
    commit: 84a308022028a55903a1916fcee516aab768ed48

Component: piglit
    tag: piglit-v1
    commit: caf60eece2d899cbfa5e92791326506630363ea4

Comment 7 Hector Velazquez 2018-01-16 21:41:45 UTC

Created attachment 136791 [details]
kernel log

Comment 8 Hector Velazquez 2018-01-16 21:42:17 UTC

Created attachment 136792 [details]
output both tests

Comment 9 Octavio 2018-02-12 21:45:46 UTC

This test still fail on CFL QA

igt@kms_fbcon_fbt@psr-suspend

using IGT-Version: 1.21-g37bd27f (x86_64) (Linux: 4.16.0-rc1-drm-intel-qa-ww7-commit-28dc2a5+ x86_64)

(kms_fbcon_fbt:15229) igt-core-WARNING: [cmd] rtcwake: write error
(kms_fbcon_fbt:15229) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_fbcon_fbt:15229) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:821:
(kms_fbcon_fbt:15229) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_fbcon_fbt:15229) igt-aux-CRITICAL: Last errno: 9, Bad file descriptor
(kms_fbcon_fbt:15229) igt-aux-CRITICAL: error: 1 != 0
Subtest psr-suspend failed.

Comment 10 Hector Velazquez 2018-03-01 16:03:50 UTC

This tests has failing on IVB QA 
Tests List:

igt@kms_frontbuffer_tracking@fbc-suspend

(kms_frontbuffer_tracking:18788) igt-core-WARNING: [cmd] rtcwake: write error[cmd] 
(kms_frontbuffer_tracking:18788) igt-aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(kms_frontbuffer_tracking:18788) igt-aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file igt_aux.c:837:
(kms_frontbuffer_tracking:18788) igt-aux-CRITICAL: Failed assertion: ret == 0
(kms_frontbuffer_tracking:18788) igt-aux-CRITICAL: error: 1 != 0
Subtest fbc-suspend failed.
. . .

software:
IGT-Version: 1.21-g1fb30f1 (x86_64) (Linux: 4.16.0-rc2-drm-intel-qa-ww8-commit-562dc33+ x86_64)

this bug has moved from the bug 104021

Comment 11 Hector Velazquez 2018-03-01 16:12:50 UTC

Created attachment 137724 [details]
output (IVB moved)

Comment 12 Hector Velazquez 2018-03-01 16:13:17 UTC

Created attachment 137726 [details]
dmesg (IVB moved)

Comment 13 Jani Saarinen 2018-03-29 07:11:44 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 14 Elizabeth 2018-04-06 22:14:18 UTC

Still valid, I got not-run on CNL, and assertions on GLK and CFL:

Results for igt@kms_fbcon_fbt@psr-suspend
Result: fail

Out	
IGT-Version: 1.21-ge3a0ed9 (x86_64) (Linux: 4.16.0-rc7-drm-intel-qa-ww14-commit-c46052c+ x86_64)
Setting 1920x1080 mode for eDP connector
Stack trace:
  #0 [__igt_fail_assert+0x101]
  #1 [subtest+0x466]
  #2 [__real_main282+0x1a0]
  #3 [main+0x23]
  #4 [__libc_start_main+0xf1]
  #5 [_start+0x29]
  #6 [<unknown>+0x29]
Subtest psr-suspend: FAIL (11.056s)
Err	
(kms_fbcon_fbt:13440) CRITICAL: Test assertion failure function subtest, file kms_fbcon_fbt.c:243:
(kms_fbcon_fbt:13440) CRITICAL: Failed assertion: feature->wait_until_enabled(drm.fd)
Subtest psr-suspend failed.

Comment 15 Dhinakaran Pandiyan 2018-07-26 07:19:20 UTC

Don't see "ERROR* [CRTC:37:pipe A] flip_done timed out" any more, checked for failures in DRM-Tip idleruns (https://intel-gfx-ci.01.org/tree/drm-tip/drmtip.html).

Not sure what fixed the problem, but it's been way too long since the bug was originally filed. Reading the last comment, looks like issues not related to the original failure are going to get conflated if we leave the bug open.

Comment 16 Francesco Balestrieri 2018-08-04 09:28:51 UTC

Closing, related issues should be handled as new bugs.

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.