Results for igt@gem_exec_suspend@basic-s3

Machine description: shard-rkl-2

Result: Dmesg-Fail 7 Warning(s)

integration-manifest git-log-oneline i915_display_info18 igt_runner18 runtimes18 results18.json results18-i915-load.json i915_display_info_post_exec18 boot18 dmesg18

DetailValue
Duration 21.97 seconds
Hostname 
shard-rkl-2
Igt-Version 
IGT-Version: 1.30-g45f75616e (x86_64) (Linux: 6.14.0-rc6-CI_DRM_16252-g5d836cddb505+ x86_64)
Out 
Using IGT_SRANDOM=1741591457 for randomisation
Opened device: /dev/dri/card0
Starting subtest: basic-S3
Starting dynamic subtest: smem
[cmd] rtcwake: assuming RTC uses UTC ...
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon Mar 10 07:24:34 2025
Stack trace:
  #0 ../lib/igt_core.c:2055 __igt_fail_assert()
  #1 ../lib/igt_aux.c:909 igt_system_suspend_autoresume()
  #2 ../tests/intel/gem_exec_suspend.c:272 run_test()
  #3 ../tests/intel/gem_exec_suspend.c:442 __igt_unique____real_main354()
  #4 ../tests/intel/gem_exec_suspend.c:354 main()
  #5 [__libc_init_first+0x8a]
  #6 [__libc_start_main+0x8b]
  #7 [_start+0x25]
Dynamic subtest smem: FAIL (21.973s)
Subtest basic-S3: FAIL (21.974s)
Err 
Starting subtest: basic-S3
Starting dynamic subtest: smem
(gem_exec_suspend:2165) igt_core-WARNING: [cmd] rtcwake: write error
(gem_exec_suspend:2165) igt_aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(gem_exec_suspend:2165) igt_aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file ../lib/igt_aux.c:828:
(gem_exec_suspend:2165) igt_aux-CRITICAL: Failed assertion: ret == 0
(gem_exec_suspend:2165) igt_aux-CRITICAL: error: 1 != 0
Dynamic subtest smem failed.
**** DEBUG ****
(gem_exec_suspend:2165) intel_allocator_reloc-DEBUG: Using reloc allocator
(gem_exec_suspend:2165) i915/gem_engine_topology-DEBUG: using pre-allocated engine list
(gem_exec_suspend:2165) DEBUG: Test requirement passed: nengine
(gem_exec_suspend:2165) i915/gem_engine_topology-DEBUG: using pre-allocated engine list
(gem_exec_suspend:2165) DEBUG: Test requirement passed: nengine
(gem_exec_suspend:2165) ioctl_wrappers-DEBUG: Test requirement passed: __gem_set_caching(fd, handle, caching) == 0
(gem_exec_suspend:2165) DEBUG: Test requirement passed: __gem_execbuf(fd, &execbuf) == 0
(gem_exec_suspend:2165) DEBUG: Verifying result
(gem_exec_suspend:2165) ioctl_wrappers-DEBUG: Test requirement passed: __gem_set_caching(fd, handle, caching) == 0
(gem_exec_suspend:2165) DEBUG: Test requirement passed: __gem_execbuf(fd, &execbuf) == 0
(gem_exec_suspend:2165) igt_aux-DEBUG: Test requirement passed: (power_dir = open("/sys/power", O_RDONLY)) >= 0
(gem_exec_suspend:2165) igt_aux-DEBUG: Test requirement passed: is_state_supported(power_dir, state)
(gem_exec_suspend:2165) igt_aux-DEBUG: Test requirement passed: test == SUSPEND_TEST_NONE || faccessat(power_dir, "pm_test", R_OK | W_OK, 0) == 0
(gem_exec_suspend:2165) igt_aux-DEBUG: Test requirement passed: !(state == SUSPEND_STATE_DISK && !igt_get_total_swap_mb())
(gem_exec_suspend:2165) igt_aux-DEBUG: Test requirement passed: ret == 0
(gem_exec_suspend:2165) igt_core-INFO: [cmd] rtcwake: assuming RTC uses UTC ...
(gem_exec_suspend:2165) igt_core-WARNING: [cmd] rtcwake: write error
(gem_exec_suspend:2165) igt_core-INFO: [cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon Mar 10 07:24:34 2025
(gem_exec_suspend:2165) igt_aux-WARNING: rtcwake failed with 1
Check dmesg for further details.
(gem_exec_suspend:2165) 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_noirq: 0
failed_resume_early: 0
failed_resume: 0
failures:
  last_failed_dev:	
			
  last_failed_errno:	-16
			0
  last_failed_step:	suspend
			
(gem_exec_suspend:2165) igt_aux-CRITICAL: Test assertion failure function suspend_via_rtcwake, file ../lib/igt_aux.c:828:
(gem_exec_suspend:2165) igt_aux-CRITICAL: Failed assertion: ret == 0
(gem_exec_suspend:2165) igt_aux-CRITICAL: error: 1 != 0
(gem_exec_suspend:2165) igt_core-INFO: Stack trace:
(gem_exec_suspend:2165) igt_core-INFO:   #0 ../lib/igt_core.c:2055 __igt_fail_assert()
(gem_exec_suspend:2165) igt_core-INFO:   #1 ../lib/igt_aux.c:909 igt_system_suspend_autoresume()
(gem_exec_suspend:2165) igt_core-INFO:   #2 ../tests/intel/gem_exec_suspend.c:272 run_test()
(gem_exec_suspend:2165) igt_core-INFO:   #3 ../tests/intel/gem_exec_suspend.c:442 __igt_unique____real_main354()
(gem_exec_suspend:2165) igt_core-INFO:   #4 ../tests/intel/gem_exec_suspend.c:354 main()
(gem_exec_suspend:2165) igt_core-INFO:   #5 [__libc_init_first+0x8a]
(gem_exec_suspend:2165) igt_core-INFO:   #6 [__libc_start_main+0x8b]
(gem_exec_suspend:2165) igt_core-INFO:   #7 [_start+0x25]
****  END  ****
Dynamic subtest smem: FAIL (21.973s)
Subtest basic-S3: FAIL (21.974s)
Dmesg

Scroll to first warning
<6> [288.424477] Console: switching to colour dummy device 80x25
<6> [288.424644] [IGT] gem_exec_suspend: executing
<7> [288.426513] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [288.427024] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [288.427198] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [288.428207] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [288.436177] [IGT] gem_exec_suspend: starting subtest basic-S3
<6> [288.436410] [IGT] gem_exec_suspend: starting dynamic subtest smem
<7> [288.436587] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [288.438534] [drm:eb_validate_vma [i915]] EINVAL at eb_validate_vma:509
<7> [288.439108] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [288.851895] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [288.869700] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [289.268517] PM: suspend entry (s2idle)
<6> [289.271540] Filesystems sync: 0.002 seconds
<6> [289.277691] Freezing user space processes
<6> [289.280697] Freezing user space processes completed (elapsed 0.002 seconds)
<6> [289.280776] OOM killer disabled.
<6> [289.280781] Freezing remaining freezable tasks
<6> [289.282344] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
<3> [291.936233] mei_me 0000:00:16.0: timer: connect/disconnect timeout.
<3> [291.937979] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect
<3> [291.938048] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14
<3> [291.938097] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message
<4> [291.938137] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel...
<3> [298.080004] mei_me 0000:00:16.0: timer: connect/disconnect timeout.
<3> [298.080847] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect
<3> [298.080917] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14
<3> [298.081004] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message
<4> [298.081047] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel...
<3> [304.223816] mei_me 0000:00:16.0: timer: connect/disconnect timeout.
<3> [304.224564] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect
<3> [304.224625] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14
<3> [304.224676] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message
<3> [304.224716] i915 0000:00:02.0: [drm] *ERROR* Failed to send tee msg for inv-stream-key-15, ret=[-14]
<7> [304.224833] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: processing event-flags 0x0000000a
<7> [304.225499] i915 0000:00:02.0: [drm:pxp_session_work [i915]] PXP: creating arb_session after invalidation
<4> [304.226188] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: Trying to reset the channel...
<3> [310.367598] mei_me 0000:00:16.0: timer: connect/disconnect timeout.
<3> [310.368336] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: cannot connect
<3> [310.368398] mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: mei_cldev_enable failed. -14
<3> [310.368446] i915 0000:00:02.0: [drm] *ERROR* Failed to send PXP TEE message
<3> [310.368482] i915 0000:00:02.0: [drm] *ERROR* Failed to send tee msg init arb session, ret=[-14]
<3> [310.368527] i915 0000:00:02.0: [drm] *ERROR* tee cmd for arb session creation failed
<7> [310.386187] PM: suspend of devices aborted after 0.146 msecs
<7> [310.386207] PM: start suspend of devices aborted after 21104.330 msecs
<3> [310.386215] PM: Some devices failed to suspend, or early wake event detected
<7> [310.386412] PM: resume of devices complete after 0.079 msecs
<6> [310.391184] OOM killer enabled.
<6> [310.391187] Restarting tasks ... done.
<5> [310.394486] random: crng reseeded on system resumption
<6> [310.394800] PM: suspend exit
<6> [310.409956] [IGT] gem_exec_suspend: finished subtest smem, FAIL
<6> [310.410165] [IGT] gem_exec_suspend: finished subtest basic-S3, FAIL
<7> [310.430729] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [310.430933] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [310.431233] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling PW_3
<7> [310.431612] i915 0000:00:02.0: [drm:intel_audio_component_get_power [i915]] restored AUD_FREQ_CNTRL to 0x8010
<7> [310.443586] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling PW_3
<7> [310.443768] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling DC_off
<7> [310.443983] i915 0000:00:02.0: [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [310.444189] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 00 to 02
<7> [310.515376] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state
<6> [310.515843] [IGT] gem_exec_suspend: exiting, ret=98
<7> [310.516128] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [310.588324] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling DC_off
<7> [310.589006] i915 0000:00:02.0: [drm:gen9_set_dc_state.part.0 [i915]] Setting DC state from 02 to 00
<7> [310.597081] i915 0000:00:02.0: [drm:drm_client_dev_restore] fbdev: ret=0
<6> [310.616201] Console: switching to colour frame buffer device 240x67
Created at 2025-03-10 08:26:17