Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2693) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2693) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2693) CRITICAL: 'val' != 'ts[1] - ts[0]' (475097136.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2693) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2693) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2693) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2693) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2693) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2693) DEBUG: slept=500480000 perf=500861654
(perf_pmu:2693) INFO: busy=475097136 idle=0
(perf_pmu:2693) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2693) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:3154) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:3154) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:3154) CRITICAL: 'val' != 'ts[1] - ts[0]' (474335625.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:3154) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:3154) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:3154) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:3154) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:3154) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:3154) DEBUG: slept=500473000 perf=500517727
(perf_pmu:3154) INFO: busy=474335625 idle=0
(perf_pmu:3154) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:3154) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2991) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2991) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2991) CRITICAL: 'val' != 'ts[1] - ts[0]' (473878073.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2991) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2991) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2991) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2991) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2991) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2991) DEBUG: slept=500714000 perf=501086562
(perf_pmu:2991) INFO: busy=473878073 idle=0
(perf_pmu:2991) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2991) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:3215) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:3215) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:3215) CRITICAL: 'val' != 'ts[1] - ts[0]' (473633699.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:3215) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:3215) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:3215) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:3215) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:3215) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:3215) DEBUG: slept=500133000 perf=500727037
(perf_pmu:3215) INFO: busy=473633699 idle=0
(perf_pmu:3215) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:3215) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2645) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2645) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2645) CRITICAL: 'val' != 'ts[1] - ts[0]' (473774323.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2645) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2645) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2645) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2645) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2645) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2645) DEBUG: slept=500392000 perf=500487703
(perf_pmu:2645) INFO: busy=473774323 idle=0
(perf_pmu:2645) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2645) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2664) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2664) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2664) CRITICAL: 'val' != 'ts[1] - ts[0]' (474680833.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2664) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2664) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2664) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2664) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2664) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2664) DEBUG: slept=500538000 perf=500586981
(perf_pmu:2664) INFO: busy=474680833 idle=0
(perf_pmu:2664) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2664) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2678) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2678) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2678) CRITICAL: 'val' != 'ts[1] - ts[0]' (474317969.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2678) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2678) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2678) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2678) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2678) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2678) DEBUG: slept=500088000 perf=500136284
(perf_pmu:2678) INFO: busy=474317969 idle=0
(perf_pmu:2678) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2678) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2633) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2633) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2633) CRITICAL: 'val' != 'ts[1] - ts[0]' (474711822.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2633) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2633) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2633) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2633) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2633) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2633) DEBUG: slept=500215000 perf=500254125
(perf_pmu:2633) INFO: busy=474711822 idle=0
(perf_pmu:2633) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2633) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2673) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2673) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2673) CRITICAL: 'val' != 'ts[1] - ts[0]' (473889687.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2673) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2673) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2673) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2673) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2673) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2673) DEBUG: slept=500063000 perf=500094855
(perf_pmu:2673) INFO: busy=473889687 idle=0
(perf_pmu:2673) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2673) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2702) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2702) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2702) CRITICAL: 'val' != 'ts[1] - ts[0]' (473928333.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2702) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2702) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2702) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2702) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2702) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2702) DEBUG: slept=500395000 perf=500443943
(perf_pmu:2702) INFO: busy=473928333 idle=0
(perf_pmu:2702) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2702) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2559) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2559) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2559) CRITICAL: 'val' != 'ts[1] - ts[0]' (472981406.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2559) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2559) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2559) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2559) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2559) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2559) DEBUG: slept=500526000 perf=500786588
(perf_pmu:2559) INFO: busy=472981406 idle=0
(perf_pmu:2559) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2559) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2603) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2603) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2603) CRITICAL: 'val' != 'ts[1] - ts[0]' (473404219.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2603) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2603) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2603) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2603) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2603) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2603) DEBUG: slept=500275000 perf=500489186
(perf_pmu:2603) INFO: busy=473404219 idle=0
(perf_pmu:2603) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2603) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2539) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2539) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2539) CRITICAL: 'val' != 'ts[1] - ts[0]' (474154479.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2539) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2539) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2539) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2539) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2539) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2539) DEBUG: slept=500382000 perf=500727187
(perf_pmu:2539) INFO: busy=474154479 idle=0
(perf_pmu:2539) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2539) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2572) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2572) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2572) CRITICAL: 'val' != 'ts[1] - ts[0]' (473259063.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2572) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2572) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2572) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2572) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2572) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2572) DEBUG: slept=500503000 perf=500793389
(perf_pmu:2572) INFO: busy=473259063 idle=0
(perf_pmu:2572) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2572) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2728) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2728) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2728) CRITICAL: 'val' != 'ts[1] - ts[0]' (473807917.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2728) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2728) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2728) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2728) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2728) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2728) DEBUG: slept=500466000 perf=500788717
(perf_pmu:2728) INFO: busy=473807917 idle=0
(perf_pmu:2728) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2728) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2598) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2598) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2598) CRITICAL: 'val' != 'ts[1] - ts[0]' (475209843.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2598) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2598) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2598) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2598) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2598) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2598) DEBUG: slept=500046000 perf=500402199
(perf_pmu:2598) INFO: busy=475209843 idle=0
(perf_pmu:2598) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2598) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2552) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2552) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2552) CRITICAL: 'val' != 'ts[1] - ts[0]' (473247136.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2552) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2552) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2552) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2552) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2552) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2552) DEBUG: slept=500155000 perf=500498652
(perf_pmu:2552) INFO: busy=473247136 idle=0
(perf_pmu:2552) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2552) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2559) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2559) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2559) CRITICAL: 'val' != 'ts[1] - ts[0]' (474168594.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2559) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2559) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2559) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2559) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2559) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2559) DEBUG: slept=500512000 perf=500841685
(perf_pmu:2559) INFO: busy=474168594 idle=0
(perf_pmu:2559) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2559) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2613) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2613) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2613) CRITICAL: 'val' != 'ts[1] - ts[0]' (473113283.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2613) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2613) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2613) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2613) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2613) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2613) DEBUG: slept=500071000 perf=500407971
(perf_pmu:2613) INFO: busy=473113283 idle=0
(perf_pmu:2613) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2613) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2584) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2584) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2584) CRITICAL: 'val' != 'ts[1] - ts[0]' (474326198.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2584) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2584) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2584) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2584) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2584) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2584) DEBUG: slept=500918000 perf=501338777
(perf_pmu:2584) INFO: busy=474326198 idle=0
(perf_pmu:2584) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2584) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2570) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2570) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2570) CRITICAL: 'val' != 'ts[1] - ts[0]' (474458595.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2570) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2570) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2570) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2570) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2570) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2570) DEBUG: slept=500373000 perf=500724855
(perf_pmu:2570) INFO: busy=474458595 idle=0
(perf_pmu:2570) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2570) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2609) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2609) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2609) CRITICAL: 'val' != 'ts[1] - ts[0]' (474471354.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2609) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2609) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2609) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2609) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2609) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2609) DEBUG: slept=501176000 perf=501591472
(perf_pmu:2609) INFO: busy=474471354 idle=0
(perf_pmu:2609) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2609) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:9069) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:9069) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:9069) CRITICAL: 'val' != 'ts[1] - ts[0]' (473219375.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:9069) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:9069) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:9069) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:9069) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:9069) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:9069) DEBUG: slept=500130000 perf=500166314
(perf_pmu:9069) INFO: busy=473219375 idle=0
(perf_pmu:9069) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:9069) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:9076) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:9076) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:9076) CRITICAL: 'val' != 'ts[1] - ts[0]' (474754010.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:9076) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:9076) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:9076) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:9076) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:9076) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:9076) DEBUG: slept=500099000 perf=500138511
(perf_pmu:9076) INFO: busy=474754010 idle=0
(perf_pmu:9076) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:9076) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2963) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2963) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2963) CRITICAL: 'val' != 'ts[1] - ts[0]' (475474375.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2963) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2963) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2963) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2963) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2963) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2963) DEBUG: slept=500129000 perf=500596927
(perf_pmu:2963) INFO: busy=475474375 idle=0
(perf_pmu:2963) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2963) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2547) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2547) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2547) CRITICAL: 'val' != 'ts[1] - ts[0]' (475676146.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2547) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2547) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2547) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2547) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2547) DEBUG: slept=500351000 perf=500759263
(perf_pmu:2547) INFO: busy=475676146 idle=0
(perf_pmu:2547) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2547) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2591) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2591) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2591) CRITICAL: 'val' != 'ts[1] - ts[0]' (473636041.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2591) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2591) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2591) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2591) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2591) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2591) DEBUG: slept=500364000 perf=500932546
(perf_pmu:2591) INFO: busy=473636041 idle=0
(perf_pmu:2591) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2591) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...

Starting subtest: busy-double-start
Starting dynamic subtest: rcs0
(perf_pmu:2499) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2499) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
(perf_pmu:2499) CRITICAL: 'val' != 'ts[1] - ts[0]' (475615313.000000 not withi...

Dynamic subtest rcs0 failed.
**** DEBUG ****
(perf_pmu:2499) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2499) intel_allocator_reloc-DEBUG: Using reloc allocator
(perf_pmu:2499) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2499) igt_dummyload-DEBUG: Test requirement passed: nengine
(perf_pmu:2499) DEBUG: Test requirement passed: !(fd < 0 && errno == ENODEV)
(perf_pmu:2499) DEBUG: slept=500231000 perf=500648820
(perf_pmu:2499) INFO: busy=475615313 idle=0
(perf_pmu:2499) CRITICAL: Test assertion failure function busy_double_start, f...
(perf_pmu:2499) CRITICAL: Failed assertion: (double)(val) <= (1.0 + (tolerance...
...