Attachment 132793 Details for Bug 101717 – debug log

debug log

gem_spin_batch-debug.log (text/plain), 78.44 KB, created by Armando Antonio on 2017-07-20 17:01:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Armando Antonio

Created: 2017-07-20 17:01:14 UTC

Size: 78.44 KB

patch

obsolete

>(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 [<unknown>+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 [<unknown>+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 [<unknown>+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 [<unknown>+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 [<unknown>+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'

Actions: View

Attachments on bug 101717: 132492 | 132493 | 132793

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.