Starting dynamic subtest: vecs1
[138.226345] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[138.226427] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[138.226510] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470788333.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[134.670842] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[134.671209] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[136.671298] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[137.178406] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[137.186292] (perf_pmu:2097) DEBUG: Test requirement passed: !(fd < 0 && errno...
[137.686733] (perf_pmu:2097) DEBUG: slept=500271000 perf=500324446
[138.225547] (perf_pmu:2097) INFO: busy=470788333 idle=0
[138.226345] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[138.226427] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[138.226510] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470788333.000...
...

Starting dynamic subtest: vecs1
[521.338361] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[521.338504] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[521.338600] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470415573.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[517.783693] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[517.784044] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[519.784422] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[520.290585] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[520.298130] (perf_pmu:2232) DEBUG: Test requirement passed: !(fd < 0 && errno...
[520.798637] (perf_pmu:2232) DEBUG: slept=500335000 perf=500387917
[521.337634] (perf_pmu:2232) INFO: busy=470415573 idle=0
[521.338361] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[521.338504] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[521.338600] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470415573.000...
...

Starting dynamic subtest: vecs1
[525.300970] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[525.301013] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[525.301060] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470750729.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[521.745266] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[521.745619] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[523.745829] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[524.252424] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[524.261214] (perf_pmu:2221) DEBUG: Test requirement passed: !(fd < 0 && errno...
[524.761722] (perf_pmu:2221) DEBUG: slept=500369000 perf=500406683
[525.300418] (perf_pmu:2221) INFO: busy=470750729 idle=0
[525.300970] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[525.301013] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[525.301060] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470750729.000...
...

Starting dynamic subtest: vecs1
[523.759684] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[523.759760] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[523.759896] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470418021.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[520.204813] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[520.205158] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[522.205422] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[522.711826] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[522.719493] (perf_pmu:2235) DEBUG: Test requirement passed: !(fd < 0 && errno...
[523.220014] (perf_pmu:2235) DEBUG: slept=500349000 perf=500403072
[523.758972] (perf_pmu:2235) INFO: busy=470418021 idle=0
[523.759684] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[523.759760] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[523.759896] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470418021.000...
...

Starting dynamic subtest: vecs1
[521.106367] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[521.106439] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[521.106513] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (471191668.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[517.551451] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[517.551795] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[519.552221] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[520.058861] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[520.066141] (perf_pmu:2219) DEBUG: Test requirement passed: !(fd < 0 && errno...
[520.566649] (perf_pmu:2219) DEBUG: slept=500351000 perf=500388738
[521.105783] (perf_pmu:2219) INFO: busy=471191668 idle=0
[521.106367] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[521.106439] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[521.106513] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (471191668.000...
...

Starting dynamic subtest: vecs1
[523.134158] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[523.134224] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[523.134302] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470857448.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[519.578673] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[519.579003] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[521.579425] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[522.086151] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[522.093591] (perf_pmu:2228) DEBUG: Test requirement passed: !(fd < 0 && errno...
[522.594124] (perf_pmu:2228) DEBUG: slept=500363000 perf=500416197
[523.133507] (perf_pmu:2228) INFO: busy=470857448 idle=0
[523.134158] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[523.134224] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[523.134302] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470857448.000...
...

Starting dynamic subtest: vecs1
[308.525765] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[308.525839] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[308.525921] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470624062.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[304.972756] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[304.973085] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[306.972824] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[307.479314] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[307.486793] (perf_pmu:2369) DEBUG: Test requirement passed: !(fd < 0 && errno...
[307.987144] (perf_pmu:2369) DEBUG: slept=500178000 perf=500230278
[308.524993] (perf_pmu:2369) INFO: busy=470624062 idle=0
[308.525765] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[308.525839] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[308.525921] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470624062.000...
...

Starting dynamic subtest: vecs1
[558.233799] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[558.233869] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[558.233944] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469559114.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[554.681192] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[554.681546] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[556.681949] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[557.187162] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[557.194096] (perf_pmu:2855) DEBUG: Test requirement passed: !(fd < 0 && errno...
[557.694626] (perf_pmu:2855) DEBUG: slept=500362000 perf=500414900
[558.233099] (perf_pmu:2855) INFO: busy=469559114 idle=0
[558.233799] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[558.233869] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[558.233944] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469559114.000...
...

Starting dynamic subtest: vecs1
[556.723056] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[556.723121] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[556.723193] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469347551.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[553.169219] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[553.169551] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[555.169886] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[555.676376] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[555.683731] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[556.184201] (perf_pmu:2877) DEBUG: slept=500321000 perf=500371377
[556.722394] (perf_pmu:2877) INFO: busy=469347551 idle=0
[556.723056] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[556.723121] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[556.723193] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469347551.000...
...

Starting dynamic subtest: vecs1
[556.043997] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[556.044073] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[556.044157] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (469198698.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[552.487795] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[552.488151] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[554.488139] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[554.995147] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[555.003721] (perf_pmu:2869) DEBUG: Test requirement passed: !(fd < 0 && errno...
[555.504146] (perf_pmu:2869) DEBUG: slept=500250000 perf=500304318
[556.043165] (perf_pmu:2869) INFO: busy=469198698 idle=0
[556.043997] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[556.044073] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[556.044157] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (469198698.000...
...

Starting dynamic subtest: vecs1
[552.178213] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[552.178262] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[552.178321] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469528698.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[548.622868] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[548.623207] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[550.623596] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[551.130717] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[551.138182] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[551.638702] (perf_pmu:2859) DEBUG: slept=500353000 perf=500406534
[552.177614] (perf_pmu:2859) INFO: busy=469528698 idle=0
[552.178213] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[552.178262] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[552.178321] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469528698.000...
...

Starting dynamic subtest: vecs1
[578.364342] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[578.364415] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[578.364498] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469210208.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[574.808481] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[574.808816] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[576.808590] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[577.316935] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[577.324130] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[577.824501] (perf_pmu:2877) DEBUG: slept=500209000 perf=500261451
[578.363581] (perf_pmu:2877) INFO: busy=469210208 idle=0
[578.364342] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[578.364415] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[578.364498] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469210208.000...
...

Starting dynamic subtest: vecs1
[555.494374] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[555.494440] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[555.494517] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469526458.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[551.940334] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[551.940673] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[553.941034] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[554.446908] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[554.454117] (perf_pmu:2876) DEBUG: Test requirement passed: !(fd < 0 && errno...
[554.954806] (perf_pmu:2876) DEBUG: slept=500359000 perf=500412529
[555.493718] (perf_pmu:2876) INFO: busy=469526458 idle=0
[555.494374] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[555.494440] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[555.494517] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469526458.000...
...

Starting dynamic subtest: vecs1
[560.555236] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[560.555295] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[560.555361] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469236719.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[557.002059] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[557.002405] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[559.002226] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[559.509076] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[559.517480] (perf_pmu:2867) DEBUG: Test requirement passed: !(fd < 0 && errno...
[560.017724] (perf_pmu:2867) DEBUG: slept=500147000 perf=500194542
[560.554631] (perf_pmu:2867) INFO: busy=469236719 idle=0
[560.555236] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[560.555295] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[560.555361] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469236719.000...
...

Starting dynamic subtest: vecs1
[574.570389] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[574.570438] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[574.570496] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469198957.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[571.013863] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[571.014223] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[573.014570] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[573.522755] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[573.530300] (perf_pmu:2875) DEBUG: Test requirement passed: !(fd < 0 && errno...
[574.030817] (perf_pmu:2875) DEBUG: slept=500348000 perf=500400937
[574.569683] (perf_pmu:2875) INFO: busy=469198957 idle=0
[574.570389] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[574.570438] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[574.570496] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469198957.000...
...

Starting dynamic subtest: vecs1
[552.309628] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[552.309695] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[552.309773] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469463802.000...

Dynamic subtest vecs1 failed.
**** DEBUG ****
[548.755224] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[548.755569] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[550.755845] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[551.262636] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[551.270022] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[551.770522] (perf_pmu:2859) DEBUG: slept=500354000 perf=500393998
[552.308957] (perf_pmu:2859) INFO: busy=469463802 idle=0
[552.309628] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[552.309695] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[552.309773] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469463802.000...
...