igt@xe_exec_reset@long-spin-reuse-many-preempt-threads on shard-bmg@xe-pw-166597v1 Test details

Detail	Value
Duration	12.05 seconds
Hostname	shard-bmg-2
Igt-Version	IGT-Version: 2.4-g22222b7d9 (x86_64) (Linux: 7.1.0-rc3-lgci-xe-xe-pw-166597v1-debug+ x86_64)
Out	Using IGT_SRANDOM=1778854198 for randomisation Opened device: /dev/dri/card0 Starting subtest: long-spin-reuse-many-preempt-threads [thread:6068] Stack trace: [thread:6068] #0 ../lib/igt_core.c:2074 __igt_fail_assert() [thread:6068] #1 [xe_exec+0x48] [thread:6068] #2 ../lib/xe/xe_legacy.c:241 xe_legacy_test_mode() [thread:6068] #3 ../tests/intel/xe_exec_reset.c:793 thread() [thread:6068] #4 [pthread_condattr_setpshared+0x684] [thread:6068] #5 [__clone+0x24c] Subtest long-spin-reuse-many-preempt-threads: FAIL (12.046s)
Err	Starting subtest: long-spin-reuse-many-preempt-threads [247.913258] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Test assertion failure function xe_exec, file ../lib/xe/xe_ioctl.c:652: [247.913308] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Failed assertion: __xe_exec(fd, exec) == 0 [247.913329] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: error: -125 != 0 [254.205237] (xe_exec_reset:6061) igt_thread-CRITICAL: Failure in a thread! Subtest long-spin-reuse-many-preempt-threads failed. ** DEBUG [247.913258] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Test assertion failure function xe_exec, file ../lib/xe/xe_ioctl.c:652: [247.913308] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Failed assertion: __xe_exec(fd, exec) == 0 [247.913329] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: error: -125 != 0 [247.916111] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: Stack trace: [247.923974] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #0 ../lib/igt_core.c:2074 __igt_fail_assert() [247.925409] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #1 [xe_exec+0x48] [247.925539] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #2 ../lib/xe/xe_legacy.c:241 xe_legacy_test_mode() [247.925912] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #3 ../tests/intel/xe_exec_reset.c:793 thread() [247.928240] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #4 [pthread_condattr_setpshared+0x684] [247.928865] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: #5 [__clone+0x24c] [254.205237] (xe_exec_reset:6061) igt_thread-CRITICAL: Failure in a thread! END ** Subtest long-spin-reuse-many-preempt-threads: FAIL (12.046s)
Dmesg	<6> [243.720460] Console: switching to colour dummy device 80x25 <6> [243.720742] [IGT] xe_exec_reset: executing <6> [243.729903] [IGT] xe_exec_reset: starting subtest long-spin-reuse-many-preempt-threads <7> [246.317327] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x2d2e2a2b <7> [246.317481] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x2c2c2c2d <7> [249.202664] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot <7> [249.203314] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=1, running_time_ms=2858, timeout_ms=5000, diff=0x031db254 <7> [249.203934] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT0: Proceeding with manual engine snapshot <7> [249.204543] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=10, running_time_ms=592, timeout_ms=5000, diff=0x00a5524f <7> [249.204813] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot <7> [249.205597] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=7, running_time_ms=2858, timeout_ms=5000, diff=0x031d90b1 <7> [249.205733] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=3, running_time_ms=2857, timeout_ms=5000, diff=0x031d5892 <7> [249.205731] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=13, running_time_ms=547, timeout_ms=5000, diff=0x0098c393 <7> [249.205935] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot <7> [249.206050] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, running_time_ms=234880, timeout_ms=5000, diff=0xffffffbf <7> [249.206495] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT0: Proceeding with manual engine snapshot <5> [249.206538] xe 0000:03:00.0: [drm] Tile0: GT1: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, flags=0x0 in xe_exec_reset [6061] <7> [249.206587] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=11, running_time_ms=2280, timeout_ms=5000, diff=0x027c7139 <7> [249.206675] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=8, running_time_ms=2304, timeout_ms=5000, diff=0x028338b3 <7> [249.206767] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=9, running_time_ms=2866, timeout_ms=5000, diff=0x031fde17 <7> [249.206847] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=12, running_time_ms=2865, timeout_ms=5000, diff=0x031fa588 <6> [249.385099] xe 0000:03:00.0: [drm] Xe device coredump has been created <6> [249.385134] xe 0000:03:00.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data <7> [249.385210] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=2, running_time_ms=3047, timeout_ms=5000, diff=0x03527478 <7> [249.385322] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, running_time_ms=2952, timeout_ms=5000, diff=0x03381bb2 <7> [249.385409] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=0, running_time_ms=2950, timeout_ms=5000, diff=0x0337865e <7> [249.385500] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=5, running_time_ms=2949, timeout_ms=5000, diff=0x03373d67 <7> [249.482849] xe 0000:03:00.0: [drm:xe_exec_ioctl [xe]] Ioctl argument check failed at drivers/gpu/drm/xe/xe_exec.c:153: q->ops->reset_status(q) <7> [254.321258] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=13, running_time_ms=2359, timeout_ms=5000, diff=0x02926339 <7> [254.321909] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=10, running_time_ms=2422, timeout_ms=5000, diff=0x02a3eb6d <6> [254.434833] pcieport 0000:00:06.0: AER: Multiple Correctable error message received from 0000:05:00.0 <4> [254.434859] nvme 0000:05:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID) <4> [254.434869] nvme 0000:05:00.0: device [15b7:5017] error status/mask=00000001/0000e000 <4> [254.434879] nvme 0000:05:00.0: [ 0] RxErr (First) <6> [255.777448] [IGT] xe_exec_reset: finished subtest long-spin-reuse-many-preempt-threads, FAIL <6> [255.781663] [IGT] xe_exec_reset: exiting, ret=98 <6> [255.784053] Console: switching to colour frame buffer device 240x67 <7> [255.810119] xe 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0

Detail

Value

Duration

12.05 seconds

Hostname

shard-bmg-2

Igt-Version

IGT-Version: 2.4-g22222b7d9 (x86_64) (Linux: 7.1.0-rc3-lgci-xe-xe-pw-166597v1-debug+ x86_64)

Out

Using IGT_SRANDOM=1778854198 for randomisation
Opened device: /dev/dri/card0
Starting subtest: long-spin-reuse-many-preempt-threads
[thread:6068] Stack trace:
[thread:6068]   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[thread:6068]   #1 [xe_exec+0x48]
[thread:6068]   #2 ../lib/xe/xe_legacy.c:241 xe_legacy_test_mode()
[thread:6068]   #3 ../tests/intel/xe_exec_reset.c:793 thread()
[thread:6068]   #4 [pthread_condattr_setpshared+0x684]
[thread:6068]   #5 [__clone+0x24c]
Subtest long-spin-reuse-many-preempt-threads: FAIL (12.046s)

Err

Starting subtest: long-spin-reuse-many-preempt-threads
[247.913258] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Test assertion failure function xe_exec, file ../lib/xe/xe_ioctl.c:652:
[247.913308] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Failed assertion: __xe_exec(fd, exec) == 0
[247.913329] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: error: -125 != 0
[254.205237] (xe_exec_reset:6061) igt_thread-CRITICAL: Failure in a thread!
Subtest long-spin-reuse-many-preempt-threads failed.
**** DEBUG ****
[247.913258] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Test assertion failure function xe_exec, file ../lib/xe/xe_ioctl.c:652:
[247.913308] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: Failed assertion: __xe_exec(fd, exec) == 0
[247.913329] (xe_exec_reset:6061) [thread:6068] xe/xe_ioctl-CRITICAL: error: -125 != 0
[247.916111] (xe_exec_reset:6061) [thread:6068] igt_core-INFO: Stack trace:
[247.923974] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #0 ../lib/igt_core.c:2074 __igt_fail_assert()
[247.925409] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #1 [xe_exec+0x48]
[247.925539] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #2 ../lib/xe/xe_legacy.c:241 xe_legacy_test_mode()
[247.925912] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #3 ../tests/intel/xe_exec_reset.c:793 thread()
[247.928240] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #4 [pthread_condattr_setpshared+0x684]
[247.928865] (xe_exec_reset:6061) [thread:6068] igt_core-INFO:   #5 [__clone+0x24c]
[254.205237] (xe_exec_reset:6061) igt_thread-CRITICAL: Failure in a thread!
****  END  ****
Subtest long-spin-reuse-many-preempt-threads: FAIL (12.046s)

Dmesg

<6> [243.720460] Console: switching to colour dummy device 80x25

<6> [243.720742] [IGT] xe_exec_reset: executing

<6> [243.729903] [IGT] xe_exec_reset: starting subtest long-spin-reuse-many-preempt-threads

<7> [246.317327] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 0 val 0x2d2e2a2b

<7> [246.317481] xe 0000:03:00.0: [drm:xe_hwmon_read [xe]] thermal data for group 1 val 0x2c2c2c2d

<7> [249.202664] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot

<7> [249.203314] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=1, running_time_ms=2858, timeout_ms=5000, diff=0x031db254

<7> [249.203934] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT0: Proceeding with manual engine snapshot

<7> [249.204543] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=10, running_time_ms=592, timeout_ms=5000, diff=0x00a5524f

<7> [249.204813] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot

<7> [249.205597] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=7, running_time_ms=2858, timeout_ms=5000, diff=0x031d90b1

<7> [249.205733] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=3, running_time_ms=2857, timeout_ms=5000, diff=0x031d5892

<7> [249.205731] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=13, running_time_ms=547, timeout_ms=5000, diff=0x0098c393

<7> [249.205935] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT1: Proceeding with manual engine snapshot

<7> [249.206050] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, running_time_ms=234880, timeout_ms=5000, diff=0xffffffbf

<7> [249.206495] xe 0000:03:00.0: [drm:xe_hw_engine_snapshot_capture [xe]] Tile0: GT0: Proceeding with manual engine snapshot

<5> [249.206538] xe 0000:03:00.0: [drm] Tile0: GT1: Timedout job: seqno=4294967169, lrc_seqno=4294967169, guc_id=6, flags=0x0 in xe_exec_reset [6061]

<7> [249.206587] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=11, running_time_ms=2280, timeout_ms=5000, diff=0x027c7139

<7> [249.206675] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=8, running_time_ms=2304, timeout_ms=5000, diff=0x028338b3

<7> [249.206767] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=9, running_time_ms=2866, timeout_ms=5000, diff=0x031fde17

<7> [249.206847] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=12, running_time_ms=2865, timeout_ms=5000, diff=0x031fa588

<6> [249.385099] xe 0000:03:00.0: [drm] Xe device coredump has been created

<6> [249.385134] xe 0000:03:00.0: [drm] Check your /sys/class/drm/card0/device/devcoredump/data

<7> [249.385210] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=2, running_time_ms=3047, timeout_ms=5000, diff=0x03527478

<7> [249.385322] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=4, running_time_ms=2952, timeout_ms=5000, diff=0x03381bb2

<7> [249.385409] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=0, running_time_ms=2950, timeout_ms=5000, diff=0x0337865e

<7> [249.385500] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT1: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=5, running_time_ms=2949, timeout_ms=5000, diff=0x03373d67

<7> [249.482849] xe 0000:03:00.0: [drm:xe_exec_ioctl [xe]] Ioctl argument check failed at drivers/gpu/drm/xe/xe_exec.c:153: q->ops->reset_status(q)

<7> [254.321258] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=13, running_time_ms=2359, timeout_ms=5000, diff=0x02926339

<7> [254.321909] xe 0000:03:00.0: [drm:guc_exec_queue_timedout_job [xe]] Tile0: GT0: Check job timeout: seqno=4294967169, lrc_seqno=4294967169, guc_id=10, running_time_ms=2422, timeout_ms=5000, diff=0x02a3eb6d

<6> [254.434833] pcieport 0000:00:06.0: AER: Multiple Correctable error message received from 0000:05:00.0

<4> [254.434859] nvme 0000:05:00.0: PCIe Bus Error: severity=Correctable, type=Physical Layer, (Receiver ID)

<4> [254.434869] nvme 0000:05:00.0: device [15b7:5017] error status/mask=00000001/0000e000

<4> [254.434879] nvme 0000:05:00.0: [ 0] RxErr (First)

<6> [255.777448] [IGT] xe_exec_reset: finished subtest long-spin-reuse-many-preempt-threads, FAIL

<6> [255.781663] [IGT] xe_exec_reset: exiting, ret=98

<6> [255.784053] Console: switching to colour frame buffer device 240x67

<7> [255.810119] xe 0000:03:00.0: [drm:drm_client_dev_restore] fbdev: ret=0

Results for igt@xe_exec_reset@long-spin-reuse-many-preempt-threads