igt@sysfs_preempt_timeout@timeout@vecs0 - fail - Failed assertion: elapsed / 1000 / 1000 < delays[i] + epsilon, Forced preemption timeout exceeded request!

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_9491/shard-skl3/igt@sysfs_preempt_timeout@timeout@vecs0.html

- Err	
- Starting subtest: timeout
- Starting dynamic subtest: vecs0
- (sysfs_preempt_timeout:17613) CRITICAL: Test assertion failure function test_timeout, file ../tests/i915/sysfs_preempt_timeout.c:221:
- (sysfs_preempt_timeout:17613) CRITICAL: Failed assertion: elapsed / 1000 / 1000 < delays[i] + epsilon
- (sysfs_preempt_timeout:17613) CRITICAL: Forced preemption timeout exceeded request!
- Dynamic subtest vecs0 failed.
- **** DEBUG ****
- (sysfs_preempt_timeout:17613) i915/gem_engine_topology-DEBUG: Test requirement passed: fstatat(engine, file, &st, 0) == 0
- (sysfs_preempt_timeout:17613) DEBUG: Initial preempt_timeout_ms:640
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) DEBUG: Test requirement passed: enable_hangcheck(i915, false)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) INFO: Minimum timeout measured as 34.419ms; setting error threshold to 68ms
- (sysfs_preempt_timeout:17613) DEBUG: Test requirement passed: epsilon < 1000
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) INFO: preempt_timeout_ms:1, elapsed=34.174ms
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) INFO: preempt_timeout_ms:50, elapsed=75.770ms
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) INFO: preempt_timeout_ms:100, elapsed=129.656ms
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: gem_class_can_store_dword(fd, class)
- (sysfs_preempt_timeout:17613) igt_dummyload-DEBUG: Test requirement passed: nengine
- (sysfs_preempt_timeout:17613) igt_debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'
- (sysfs_preempt_timeout:17613) INFO: preempt_timeout_ms:500, elapsed=599.819ms
- (sysfs_preempt_timeout:17613) CRITICAL: Test assertion failure function test_timeout, file ../tests/i915/sysfs_preempt_timeout.c:221:
- (sysfs_preempt_timeout:17613) CRITICAL: Failed assertion: elapsed / 1000 / 1000 < delays[i] + epsilon
- (sysfs_preempt_timeout:17613) CRITICAL: Forced preemption timeout exceeded request!
- (sysfs_preempt_timeout:17613) igt_core-INFO: Stack trace:
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #0 ../lib/igt_core.c:1727 __igt_fail_assert()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #1 ../tests/i915/sysfs_preempt_timeout.c:224 test_timeout()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #2 ../lib/i915/gem_engine_topology.c:532 dyn_sysfs_engines()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #3 ../tests/i915/sysfs_preempt_timeout.c:320 __real_main289()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #4 ../tests/i915/sysfs_preempt_timeout.c:289 main()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #5 ../csu/libc-start.c:344 __libc_start_main()
- (sysfs_preempt_timeout:17613) igt_core-INFO:   #6 [_start+0x2a]
- ****  END  ****
- Dynamic subtest vecs0: FAIL (1.696s)