(12:02 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-blt --d sudo: ./gem_spin_batch: command not found (12:03 PM) [gfx@IVB-1-0XR1GT] [tests]$ : cd (12:03 PM) [gfx@IVB-1-0XR1GT] [~]$ : cd ~/intel-graphics/intel-gpu-tools/tests/ (12:03 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-blt --d IGT-Version: 1.19-ga8bfef7 (x86_64) (Linux: 4.8.0-59-generic x86_64) (gem_spin_batch:4207) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (gem_spin_batch:4207) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_spin_batch:4207) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4207) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4207) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4207) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4207) igt-core-DEBUG: Starting subtest: basic-blt (gem_spin_batch:4207) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 0: interval=163.610324ms (target 100ms), elapsed 163.633467ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 1: interval=160.453778ms (target 100ms), elapsed 324.166420ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 2: interval=100.169747ms (target 100ms), elapsed 424.424723ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 3: interval=110.476003ms (target 100ms), elapsed 534.969479ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 4: interval=120.305165ms (target 100ms), elapsed 655.368044ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 5: interval=160.494754ms (target 100ms), elapsed 815.921846ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 6: interval=130.401648ms (target 100ms), elapsed 946.402467ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 7: interval=100.343514ms (target 100ms), elapsed 1046.814304ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 8: interval=130.325310ms (target 100ms), elapsed 1177.218325ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 9: interval=100.300596ms (target 100ms), elapsed 1277.624269ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 10: interval=150.381386ms (target 100ms), elapsed 1428.071056ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 11: interval=160.545244ms (target 100ms), elapsed 1588.668697ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 12: interval=110.344824ms (target 100ms), elapsed 1699.091076ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 13: interval=110.295563ms (target 100ms), elapsed 1809.454756ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 14: interval=110.323212ms (target 100ms), elapsed 1919.846460ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 15: interval=100.329297ms (target 100ms), elapsed 2020.233534ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 16: interval=100.296476ms (target 100ms), elapsed 2120.593821ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 17: interval=130.320305ms (target 100ms), elapsed 2250.981937ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 18: interval=120.413456ms (target 100ms), elapsed 2371.472941ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 19: interval=110.295348ms (target 100ms), elapsed 2481.833497ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 20: interval=100.319743ms (target 100ms), elapsed 2582.222318ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 21: interval=110.285728ms (target 100ms), elapsed 2692.559627ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 22: interval=120.336215ms (target 100ms), elapsed 2812.976946ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 23: interval=113.320983ms (target 100ms), elapsed 2926.366822ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 24: interval=127.424441ms (target 100ms), elapsed 3053.877163ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 25: interval=130.347381ms (target 100ms), elapsed 3184.290662ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 26: interval=110.302678ms (target 100ms), elapsed 3294.672437ms Completed 27 loops in 3294689995 ns, target 32 (gem_spin_batch:4207) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4207) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4207) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2700000000 not within 5% tolerance of 3294689995) Stack trace: #0 [__igt_fail_assert+0x101] #1 [spin.constprop.1+0x23e] #2 [+0x23e] Subtest basic-blt failed. **** DEBUG **** (gem_spin_batch:4207) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 0: interval=163.610324ms (target 100ms), elapsed 163.633467ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 1: interval=160.453778ms (target 100ms), elapsed 324.166420ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 2: interval=100.169747ms (target 100ms), elapsed 424.424723ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 3: interval=110.476003ms (target 100ms), elapsed 534.969479ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 4: interval=120.305165ms (target 100ms), elapsed 655.368044ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 5: interval=160.494754ms (target 100ms), elapsed 815.921846ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 6: interval=130.401648ms (target 100ms), elapsed 946.402467ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 7: interval=100.343514ms (target 100ms), elapsed 1046.814304ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 8: interval=130.325310ms (target 100ms), elapsed 1177.218325ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 9: interval=100.300596ms (target 100ms), elapsed 1277.624269ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 10: interval=150.381386ms (target 100ms), elapsed 1428.071056ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 11: interval=160.545244ms (target 100ms), elapsed 1588.668697ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 12: interval=110.344824ms (target 100ms), elapsed 1699.091076ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 13: interval=110.295563ms (target 100ms), elapsed 1809.454756ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 14: interval=110.323212ms (target 100ms), elapsed 1919.846460ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 15: interval=100.329297ms (target 100ms), elapsed 2020.233534ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 16: interval=100.296476ms (target 100ms), elapsed 2120.593821ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 17: interval=130.320305ms (target 100ms), elapsed 2250.981937ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 18: interval=120.413456ms (target 100ms), elapsed 2371.472941ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 19: interval=110.295348ms (target 100ms), elapsed 2481.833497ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 20: interval=100.319743ms (target 100ms), elapsed 2582.222318ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 21: interval=110.285728ms (target 100ms), elapsed 2692.559627ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 22: interval=120.336215ms (target 100ms), elapsed 2812.976946ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 23: interval=113.320983ms (target 100ms), elapsed 2926.366822ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 24: interval=127.424441ms (target 100ms), elapsed 3053.877163ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 25: interval=130.347381ms (target 100ms), elapsed 3184.290662ms (gem_spin_batch:4207) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4207) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4207) DEBUG: loop 26: interval=110.302678ms (target 100ms), elapsed 3294.672437ms (gem_spin_batch:4207) INFO: Completed 27 loops in 3294689995 ns, target 32 (gem_spin_batch:4207) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4207) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4207) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2700000000 not within 5% tolerance of 3294689995) **** END **** Subtest basic-blt: FAIL (3.295s) (gem_spin_batch:4207) igt-core-DEBUG: Exiting with status code 99 (gem_spin_batch:4207) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (12:03 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-bsd --d IGT-Version: 1.19-ga8bfef7 (x86_64) (Linux: 4.8.0-59-generic x86_64) (gem_spin_batch:4262) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (gem_spin_batch:4262) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_spin_batch:4262) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4262) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4262) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4262) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4262) igt-core-DEBUG: Starting subtest: basic-bsd (gem_spin_batch:4262) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 0: interval=131.309255ms (target 100ms), elapsed 131.347182ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 1: interval=200.604345ms (target 100ms), elapsed 332.025635ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 2: interval=120.359499ms (target 100ms), elapsed 452.474436ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 3: interval=110.305766ms (target 100ms), elapsed 562.857498ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 4: interval=100.075966ms (target 100ms), elapsed 663.002171ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 5: interval=130.573283ms (target 100ms), elapsed 793.654180ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 6: interval=190.582040ms (target 100ms), elapsed 984.328094ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 7: interval=170.496269ms (target 100ms), elapsed 1154.915693ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 8: interval=110.359093ms (target 100ms), elapsed 1265.350198ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 9: interval=100.428944ms (target 100ms), elapsed 1365.859297ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 10: interval=100.237925ms (target 100ms), elapsed 1466.162809ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 11: interval=140.313302ms (target 100ms), elapsed 1606.541171ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 12: interval=110.322713ms (target 100ms), elapsed 1716.945603ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 13: interval=100.319681ms (target 100ms), elapsed 1817.319093ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 14: interval=150.450745ms (target 100ms), elapsed 1967.833447ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 15: interval=130.423015ms (target 100ms), elapsed 2098.315296ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 16: interval=120.308779ms (target 100ms), elapsed 2218.706680ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 17: interval=120.330621ms (target 100ms), elapsed 2339.112838ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 18: interval=150.448067ms (target 100ms), elapsed 2489.646059ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 19: interval=140.430987ms (target 100ms), elapsed 2630.174736ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 20: interval=130.362887ms (target 100ms), elapsed 2760.603877ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 21: interval=120.350888ms (target 100ms), elapsed 2881.030004ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 22: interval=140.406075ms (target 100ms), elapsed 3021.526689ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 23: interval=160.536279ms (target 100ms), elapsed 3182.116103ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 24: interval=110.295091ms (target 100ms), elapsed 3292.485052ms Completed 25 loops in 3292514632 ns, target 32 (gem_spin_batch:4262) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4262) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4262) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3292514632) Stack trace: #0 [__igt_fail_assert+0x101] #1 [spin.constprop.1+0x23e] #2 [+0x23e] Subtest basic-bsd failed. **** DEBUG **** (gem_spin_batch:4262) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 0: interval=131.309255ms (target 100ms), elapsed 131.347182ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 1: interval=200.604345ms (target 100ms), elapsed 332.025635ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 2: interval=120.359499ms (target 100ms), elapsed 452.474436ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 3: interval=110.305766ms (target 100ms), elapsed 562.857498ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 4: interval=100.075966ms (target 100ms), elapsed 663.002171ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 5: interval=130.573283ms (target 100ms), elapsed 793.654180ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 6: interval=190.582040ms (target 100ms), elapsed 984.328094ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 7: interval=170.496269ms (target 100ms), elapsed 1154.915693ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 8: interval=110.359093ms (target 100ms), elapsed 1265.350198ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 9: interval=100.428944ms (target 100ms), elapsed 1365.859297ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 10: interval=100.237925ms (target 100ms), elapsed 1466.162809ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 11: interval=140.313302ms (target 100ms), elapsed 1606.541171ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 12: interval=110.322713ms (target 100ms), elapsed 1716.945603ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 13: interval=100.319681ms (target 100ms), elapsed 1817.319093ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 14: interval=150.450745ms (target 100ms), elapsed 1967.833447ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 15: interval=130.423015ms (target 100ms), elapsed 2098.315296ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 16: interval=120.308779ms (target 100ms), elapsed 2218.706680ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 17: interval=120.330621ms (target 100ms), elapsed 2339.112838ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 18: interval=150.448067ms (target 100ms), elapsed 2489.646059ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 19: interval=140.430987ms (target 100ms), elapsed 2630.174736ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 20: interval=130.362887ms (target 100ms), elapsed 2760.603877ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 21: interval=120.350888ms (target 100ms), elapsed 2881.030004ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 22: interval=140.406075ms (target 100ms), elapsed 3021.526689ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 23: interval=160.536279ms (target 100ms), elapsed 3182.116103ms (gem_spin_batch:4262) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4262) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4262) DEBUG: loop 24: interval=110.295091ms (target 100ms), elapsed 3292.485052ms (gem_spin_batch:4262) INFO: Completed 25 loops in 3292514632 ns, target 32 (gem_spin_batch:4262) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4262) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4262) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3292514632) **** END **** Subtest basic-bsd: FAIL (3.293s) (gem_spin_batch:4262) igt-core-DEBUG: Exiting with status code 99 (gem_spin_batch:4262) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (12:04 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-bsd1 --d IGT-Version: 1.19-ga8bfef7 (x86_64) (Linux: 4.8.0-59-generic x86_64) (gem_spin_batch:4304) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (gem_spin_batch:4304) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_spin_batch:4304) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4304) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4304) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4304) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4304) igt-core-DEBUG: Starting subtest: basic-bsd1 (gem_spin_batch:4304) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 0: interval=120.602174ms (target 100ms), elapsed 120.642949ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 1: interval=130.399472ms (target 100ms), elapsed 251.115089ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 2: interval=110.325670ms (target 100ms), elapsed 361.505715ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 3: interval=120.311567ms (target 100ms), elapsed 481.879921ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 4: interval=100.343045ms (target 100ms), elapsed 582.297181ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 5: interval=130.312708ms (target 100ms), elapsed 712.671600ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 6: interval=140.514777ms (target 100ms), elapsed 853.239344ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 7: interval=170.471720ms (target 100ms), elapsed 1023.785886ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 8: interval=120.397739ms (target 100ms), elapsed 1144.254396ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 9: interval=120.302200ms (target 100ms), elapsed 1264.614031ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 10: interval=180.619749ms (target 100ms), elapsed 1445.292030ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 11: interval=170.495970ms (target 100ms), elapsed 1615.866416ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 12: interval=140.471418ms (target 100ms), elapsed 1756.391178ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 13: interval=110.354572ms (target 100ms), elapsed 1866.826826ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 14: interval=130.308834ms (target 100ms), elapsed 1997.214308ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 15: interval=130.404591ms (target 100ms), elapsed 2127.693317ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 16: interval=110.307357ms (target 100ms), elapsed 2238.110216ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 17: interval=120.364790ms (target 100ms), elapsed 2358.543162ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 18: interval=110.318000ms (target 100ms), elapsed 2468.915051ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 19: interval=120.321079ms (target 100ms), elapsed 2589.315973ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 20: interval=110.319164ms (target 100ms), elapsed 2699.698862ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 21: interval=148.926315ms (target 100ms), elapsed 2848.700437ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 22: interval=121.849471ms (target 100ms), elapsed 2970.648483ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 23: interval=160.516584ms (target 100ms), elapsed 3131.220939ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 24: interval=130.449891ms (target 100ms), elapsed 3261.727096ms Completed 25 loops in 3261747708 ns, target 32 (gem_spin_batch:4304) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4304) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4304) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3261747708) Stack trace: #0 [__igt_fail_assert+0x101] #1 [spin.constprop.1+0x23e] #2 [+0x23e] Subtest basic-bsd1 failed. **** DEBUG **** (gem_spin_batch:4304) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 0: interval=120.602174ms (target 100ms), elapsed 120.642949ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 1: interval=130.399472ms (target 100ms), elapsed 251.115089ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 2: interval=110.325670ms (target 100ms), elapsed 361.505715ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 3: interval=120.311567ms (target 100ms), elapsed 481.879921ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 4: interval=100.343045ms (target 100ms), elapsed 582.297181ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 5: interval=130.312708ms (target 100ms), elapsed 712.671600ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 6: interval=140.514777ms (target 100ms), elapsed 853.239344ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 7: interval=170.471720ms (target 100ms), elapsed 1023.785886ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 8: interval=120.397739ms (target 100ms), elapsed 1144.254396ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 9: interval=120.302200ms (target 100ms), elapsed 1264.614031ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 10: interval=180.619749ms (target 100ms), elapsed 1445.292030ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 11: interval=170.495970ms (target 100ms), elapsed 1615.866416ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 12: interval=140.471418ms (target 100ms), elapsed 1756.391178ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 13: interval=110.354572ms (target 100ms), elapsed 1866.826826ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 14: interval=130.308834ms (target 100ms), elapsed 1997.214308ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 15: interval=130.404591ms (target 100ms), elapsed 2127.693317ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 16: interval=110.307357ms (target 100ms), elapsed 2238.110216ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 17: interval=120.364790ms (target 100ms), elapsed 2358.543162ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 18: interval=110.318000ms (target 100ms), elapsed 2468.915051ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 19: interval=120.321079ms (target 100ms), elapsed 2589.315973ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 20: interval=110.319164ms (target 100ms), elapsed 2699.698862ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 21: interval=148.926315ms (target 100ms), elapsed 2848.700437ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 22: interval=121.849471ms (target 100ms), elapsed 2970.648483ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 23: interval=160.516584ms (target 100ms), elapsed 3131.220939ms (gem_spin_batch:4304) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4304) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4304) DEBUG: loop 24: interval=130.449891ms (target 100ms), elapsed 3261.727096ms (gem_spin_batch:4304) INFO: Completed 25 loops in 3261747708 ns, target 32 (gem_spin_batch:4304) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4304) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4304) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3261747708) **** END **** Subtest basic-bsd1: FAIL (3.263s) (gem_spin_batch:4304) igt-core-DEBUG: Exiting with status code 99 (gem_spin_batch:4304) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (12:04 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-bsd2 --d IGT-Version: 1.19-ga8bfef7 (x86_64) (Linux: 4.8.0-59-generic x86_64) (gem_spin_batch:4358) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (gem_spin_batch:4358) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_spin_batch:4358) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4358) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4358) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4358) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4358) igt-core-DEBUG: Starting subtest: basic-bsd2 (gem_spin_batch:4358) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 0: interval=111.061552ms (target 100ms), elapsed 111.098346ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 1: interval=160.350349ms (target 100ms), elapsed 271.547496ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 2: interval=100.355864ms (target 100ms), elapsed 371.981529ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 3: interval=100.302976ms (target 100ms), elapsed 472.352336ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 4: interval=170.445156ms (target 100ms), elapsed 642.864853ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 5: interval=120.331538ms (target 100ms), elapsed 763.296781ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 6: interval=120.333513ms (target 100ms), elapsed 883.724383ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 7: interval=140.464777ms (target 100ms), elapsed 1024.245696ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 8: interval=102.738727ms (target 100ms), elapsed 1127.064121ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 9: interval=157.987371ms (target 100ms), elapsed 1285.121675ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 10: interval=180.632051ms (target 100ms), elapsed 1465.806896ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 11: interval=140.440871ms (target 100ms), elapsed 1606.309655ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 12: interval=120.318704ms (target 100ms), elapsed 1726.692180ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 13: interval=110.456282ms (target 100ms), elapsed 1837.200039ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 14: interval=130.319622ms (target 100ms), elapsed 1967.598487ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 15: interval=100.326920ms (target 100ms), elapsed 2067.993150ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 16: interval=130.333628ms (target 100ms), elapsed 2198.395242ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 17: interval=130.313380ms (target 100ms), elapsed 2328.813037ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 18: interval=190.650936ms (target 100ms), elapsed 2519.516607ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 19: interval=120.350093ms (target 100ms), elapsed 2639.951594ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 20: interval=120.309609ms (target 100ms), elapsed 2760.339462ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 21: interval=120.373151ms (target 100ms), elapsed 2880.777122ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 22: interval=140.419238ms (target 100ms), elapsed 3021.262436ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 23: interval=110.342242ms (target 100ms), elapsed 3131.679854ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 24: interval=120.336864ms (target 100ms), elapsed 3252.078866ms Completed 25 loops in 3252107236 ns, target 32 (gem_spin_batch:4358) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4358) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4358) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3252107236) Stack trace: #0 [__igt_fail_assert+0x101] #1 [spin.constprop.1+0x23e] #2 [+0x23e] Subtest basic-bsd2 failed. **** DEBUG **** (gem_spin_batch:4358) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 0: interval=111.061552ms (target 100ms), elapsed 111.098346ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 1: interval=160.350349ms (target 100ms), elapsed 271.547496ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 2: interval=100.355864ms (target 100ms), elapsed 371.981529ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 3: interval=100.302976ms (target 100ms), elapsed 472.352336ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 4: interval=170.445156ms (target 100ms), elapsed 642.864853ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 5: interval=120.331538ms (target 100ms), elapsed 763.296781ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 6: interval=120.333513ms (target 100ms), elapsed 883.724383ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 7: interval=140.464777ms (target 100ms), elapsed 1024.245696ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 8: interval=102.738727ms (target 100ms), elapsed 1127.064121ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 9: interval=157.987371ms (target 100ms), elapsed 1285.121675ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 10: interval=180.632051ms (target 100ms), elapsed 1465.806896ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 11: interval=140.440871ms (target 100ms), elapsed 1606.309655ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 12: interval=120.318704ms (target 100ms), elapsed 1726.692180ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 13: interval=110.456282ms (target 100ms), elapsed 1837.200039ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 14: interval=130.319622ms (target 100ms), elapsed 1967.598487ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 15: interval=100.326920ms (target 100ms), elapsed 2067.993150ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 16: interval=130.333628ms (target 100ms), elapsed 2198.395242ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 17: interval=130.313380ms (target 100ms), elapsed 2328.813037ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 18: interval=190.650936ms (target 100ms), elapsed 2519.516607ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 19: interval=120.350093ms (target 100ms), elapsed 2639.951594ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 20: interval=120.309609ms (target 100ms), elapsed 2760.339462ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 21: interval=120.373151ms (target 100ms), elapsed 2880.777122ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 22: interval=140.419238ms (target 100ms), elapsed 3021.262436ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 23: interval=110.342242ms (target 100ms), elapsed 3131.679854ms (gem_spin_batch:4358) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4358) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4358) DEBUG: loop 24: interval=120.336864ms (target 100ms), elapsed 3252.078866ms (gem_spin_batch:4358) INFO: Completed 25 loops in 3252107236 ns, target 32 (gem_spin_batch:4358) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4358) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4358) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2500000000 not within 5% tolerance of 3252107236) **** END **** Subtest basic-bsd2: FAIL (3.253s) (gem_spin_batch:4358) igt-core-DEBUG: Exiting with status code 99 (gem_spin_batch:4358) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (12:04 PM) [gfx@IVB-1-0XR1GT] [tests]$ : sudo -E ./gem_spin_batch --r basic-render --d IGT-Version: 1.19-ga8bfef7 (x86_64) (Linux: 4.8.0-59-generic x86_64) (gem_spin_batch:4407) igt-core-DEBUG: Test requirement passed: !igt_run_in_simulation() (gem_spin_batch:4407) drmtest-DEBUG: Test requirement passed: !(fd<0) (gem_spin_batch:4407) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4407) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4407) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4407) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0' (gem_spin_batch:4407) igt-core-DEBUG: Starting subtest: basic-render (gem_spin_batch:4407) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 0: interval=161.715364ms (target 100ms), elapsed 161.753812ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 1: interval=220.626408ms (target 100ms), elapsed 382.451046ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 2: interval=140.432081ms (target 100ms), elapsed 522.939299ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 3: interval=150.500103ms (target 100ms), elapsed 673.498400ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 4: interval=160.514514ms (target 100ms), elapsed 834.068182ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 5: interval=130.462049ms (target 100ms), elapsed 964.595901ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 6: interval=110.347429ms (target 100ms), elapsed 1075.009703ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 7: interval=140.342790ms (target 100ms), elapsed 1215.409964ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 8: interval=160.563121ms (target 100ms), elapsed 1376.028601ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 9: interval=150.758046ms (target 100ms), elapsed 1526.842642ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 10: interval=160.179888ms (target 100ms), elapsed 1687.078116ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 11: interval=140.461559ms (target 100ms), elapsed 1827.600055ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 12: interval=180.535176ms (target 100ms), elapsed 2008.192939ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 13: interval=180.605897ms (target 100ms), elapsed 2188.855664ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 14: interval=180.601249ms (target 100ms), elapsed 2369.516843ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 15: interval=130.417057ms (target 100ms), elapsed 2499.991327ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 16: interval=190.554798ms (target 100ms), elapsed 2690.606382ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 17: interval=110.337924ms (target 100ms), elapsed 2800.997482ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 18: interval=160.567498ms (target 100ms), elapsed 2961.619819ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 19: interval=130.339347ms (target 100ms), elapsed 3092.026168ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 20: interval=130.423112ms (target 100ms), elapsed 3222.504075ms Completed 21 loops in 3222523507 ns, target 32 (gem_spin_batch:4407) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4407) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4407) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2100000000 not within 5% tolerance of 3222523507) Stack trace: #0 [__igt_fail_assert+0x101] #1 [spin.constprop.1+0x23e] #2 [+0x23e] Subtest basic-render failed. **** DEBUG **** (gem_spin_batch:4407) drmtest-DEBUG: Test requirement passed: is_i915_device(fd) && has_known_intel_chipset(fd) (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: err == 0 (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 0: interval=161.715364ms (target 100ms), elapsed 161.753812ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 1: interval=220.626408ms (target 100ms), elapsed 382.451046ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 2: interval=140.432081ms (target 100ms), elapsed 522.939299ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 3: interval=150.500103ms (target 100ms), elapsed 673.498400ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 4: interval=160.514514ms (target 100ms), elapsed 834.068182ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 5: interval=130.462049ms (target 100ms), elapsed 964.595901ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 6: interval=110.347429ms (target 100ms), elapsed 1075.009703ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 7: interval=140.342790ms (target 100ms), elapsed 1215.409964ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 8: interval=160.563121ms (target 100ms), elapsed 1376.028601ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 9: interval=150.758046ms (target 100ms), elapsed 1526.842642ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 10: interval=160.179888ms (target 100ms), elapsed 1687.078116ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 11: interval=140.461559ms (target 100ms), elapsed 1827.600055ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 12: interval=180.535176ms (target 100ms), elapsed 2008.192939ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 13: interval=180.605897ms (target 100ms), elapsed 2188.855664ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 14: interval=180.601249ms (target 100ms), elapsed 2369.516843ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 15: interval=130.417057ms (target 100ms), elapsed 2499.991327ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 16: interval=190.554798ms (target 100ms), elapsed 2690.606382ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 17: interval=110.337924ms (target 100ms), elapsed 2800.997482ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 18: interval=160.567498ms (target 100ms), elapsed 2961.619819ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 19: interval=130.339347ms (target 100ms), elapsed 3092.026168ms (gem_spin_batch:4407) ioctl-wrappers-DEBUG: Test requirement passed: gem_has_ring(fd, ring) (gem_spin_batch:4407) igt-dummyload-DEBUG: Test requirement passed: nengine (gem_spin_batch:4407) DEBUG: loop 20: interval=130.423112ms (target 100ms), elapsed 3222.504075ms (gem_spin_batch:4407) INFO: Completed 21 loops in 3222523507 ns, target 32 (gem_spin_batch:4407) CRITICAL: Test assertion failure function spin, file gem_spin_batch.c:66: (gem_spin_batch:4407) CRITICAL: Failed assertion: 100 * timeout_100ms * loops <= (100 + 5) * elapsed && 100 * timeout_100ms * loops >= (100 - 5) * elapsed (gem_spin_batch:4407) CRITICAL: 'timeout_100ms * loops' != 'elapsed' (2100000000 not within 5% tolerance of 3222523507) **** END **** Subtest basic-render: FAIL (3.223s) (gem_spin_batch:4407) igt-core-DEBUG: Exiting with status code 99 (gem_spin_batch:4407) igt-debugfs-DEBUG: Opening debugfs directory '/sys/kernel/debug/dri/0'