Starting dynamic subtest: vcs0
[127.521863] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[127.521924] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[127.521985] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470683906.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[123.965782] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[123.966754] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[125.966848] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[126.473775] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[126.480966] (perf_pmu:2097) DEBUG: Test requirement passed: !(fd < 0 && errno...
[126.981390] (perf_pmu:2097) DEBUG: slept=500253000 perf=500303958
[127.521094] (perf_pmu:2097) INFO: busy=470683906 idle=0
[127.521863] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[127.521924] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[127.521985] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470683906.000...
...

Starting dynamic subtest: vcs0
[510.621384] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[510.621457] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[510.621543] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470594427.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[507.066909] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[507.067294] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[509.067724] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[509.574319] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[509.581683] (perf_pmu:2232) DEBUG: Test requirement passed: !(fd < 0 && errno...
[510.082191] (perf_pmu:2232) DEBUG: slept=500332000 perf=500384210
[510.620745] (perf_pmu:2232) INFO: busy=470594427 idle=0
[510.621384] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[510.621457] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[510.621543] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470594427.000...
...

Starting dynamic subtest: vcs0
[514.589120] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[514.589169] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[514.589230] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470765834.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[511.034748] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[511.035108] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[513.035568] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[513.541995] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[513.549321] (perf_pmu:2221) DEBUG: Test requirement passed: !(fd < 0 && errno...
[514.049866] (perf_pmu:2221) DEBUG: slept=500384000 perf=500429564
[514.588545] (perf_pmu:2221) INFO: busy=470765834 idle=0
[514.589120] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[514.589169] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[514.589230] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470765834.000...
...

Starting dynamic subtest: vcs0
[513.044360] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[513.044437] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[513.044517] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470400365.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[509.489321] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[509.489748] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[511.490037] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[511.996767] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[512.004224] (perf_pmu:2235) DEBUG: Test requirement passed: !(fd < 0 && errno...
[512.504778] (perf_pmu:2235) DEBUG: slept=500384000 perf=500435007
[513.043641] (perf_pmu:2235) INFO: busy=470400365 idle=0
[513.044360] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[513.044437] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[513.044517] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470400365.000...
...

Starting dynamic subtest: vcs0
[510.391386] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[510.391436] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[510.391496] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (470717136.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[506.836525] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[506.836866] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[508.837297] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[509.344686] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[509.352269] (perf_pmu:2219) DEBUG: Test requirement passed: !(fd < 0 && errno...
[509.852570] (perf_pmu:2219) DEBUG: slept=500153000 perf=500188863
[510.390832] (perf_pmu:2219) INFO: busy=470717136 idle=0
[510.391386] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[510.391436] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[510.391496] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (470717136.000...
...

Starting dynamic subtest: vcs0
[512.427396] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[512.427463] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[512.427538] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470626979.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[508.871566] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[508.872010] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[510.872339] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[511.379095] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[511.387569] (perf_pmu:2228) DEBUG: Test requirement passed: !(fd < 0 && errno...
[511.888088] (perf_pmu:2228) DEBUG: slept=500357000 perf=500396239
[512.426798] (perf_pmu:2228) INFO: busy=470626979 idle=0
[512.427396] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[512.427463] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[512.427538] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470626979.000...
...

Starting dynamic subtest: vcs0
[297.821041] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[297.821100] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[297.821159] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470534114.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[294.267757] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[294.268158] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[296.268020] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[296.774467] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[296.782115] (perf_pmu:2369) DEBUG: Test requirement passed: !(fd < 0 && errno...
[297.282462] (perf_pmu:2369) DEBUG: slept=500174000 perf=500224479
[297.820323] (perf_pmu:2369) INFO: busy=470534114 idle=0
[297.821041] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[297.821100] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[297.821159] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470534114.000...
...

Starting dynamic subtest: vcs0
[547.523350] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[547.523423] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[547.523501] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469203072.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[543.966494] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[543.966914] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[545.967321] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[546.474131] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[546.482672] (perf_pmu:2855) DEBUG: Test requirement passed: !(fd < 0 && errno...
[546.983191] (perf_pmu:2855) DEBUG: slept=500356000 perf=500405829
[547.522787] (perf_pmu:2855) INFO: busy=469203072 idle=0
[547.523350] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[547.523423] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[547.523501] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469203072.000...
...

Starting dynamic subtest: vcs0
[546.013178] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[546.013237] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[546.013302] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469187136.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[542.459645] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[542.460061] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[544.460399] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.966813] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.974110] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[545.474575] (perf_pmu:2877) DEBUG: slept=500315000 perf=500365210
[546.012587] (perf_pmu:2877) INFO: busy=469187136 idle=0
[546.013178] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[546.013237] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[546.013302] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469187136.000...
...

Starting dynamic subtest: vcs0
[545.332075] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[545.332154] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.332308] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (468951354.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[541.777433] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[541.777826] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[543.777732] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.285013] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.292606] (perf_pmu:2869) DEBUG: Test requirement passed: !(fd < 0 && errno...
[544.793036] (perf_pmu:2869) DEBUG: slept=500253000 perf=500306515
[545.331394] (perf_pmu:2869) INFO: busy=468951354 idle=0
[545.332075] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[545.332154] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.332308] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (468951354.000...
...

Starting dynamic subtest: vcs0
[541.477160] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[541.477209] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[541.477266] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469264167.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[537.921910] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[537.922265] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[539.922707] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[540.429309] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[540.437313] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[540.937843] (perf_pmu:2859) DEBUG: slept=500366000 perf=500417765
[541.476579] (perf_pmu:2859) INFO: busy=469264167 idle=0
[541.477160] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[541.477209] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[541.477266] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469264167.000...
...

Starting dynamic subtest: vcs0
[567.654934] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[567.655006] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[567.655151] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469172864.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[564.100615] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[564.100963] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[566.100950] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[566.607495] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[566.615019] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[567.115441] (perf_pmu:2877) DEBUG: slept=500249000 perf=500299699
[567.654247] (perf_pmu:2877) INFO: busy=469172864 idle=0
[567.654934] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[567.655006] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[567.655151] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469172864.000...
...

Starting dynamic subtest: vcs0
[544.782606] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[544.782672] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[544.782747] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469208958.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[541.226782] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[541.227226] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[543.227626] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[543.734314] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[543.742408] (perf_pmu:2876) DEBUG: Test requirement passed: !(fd < 0 && errno...
[544.243005] (perf_pmu:2876) DEBUG: slept=500353000 perf=500404180
[544.781955] (perf_pmu:2876) INFO: busy=469208958 idle=0
[544.782606] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[544.782672] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[544.782747] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469208958.000...
...

Starting dynamic subtest: vcs0
[549.824981] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[549.825038] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[549.825097] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469143698.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[546.271527] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[546.271845] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[548.271837] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[548.778148] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[548.785347] (perf_pmu:2867) DEBUG: Test requirement passed: !(fd < 0 && errno...
[549.285760] (perf_pmu:2867) DEBUG: slept=500263000 perf=500312679
[549.824415] (perf_pmu:2867) INFO: busy=469143698 idle=0
[549.824981] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[549.825038] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[549.825097] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469143698.000...
...

Starting dynamic subtest: vcs0
[563.856149] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[563.856292] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[563.856378] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469236354.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[560.299784] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[560.300115] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[562.300516] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[562.807180] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[562.814454] (perf_pmu:2875) DEBUG: Test requirement passed: !(fd < 0 && errno...
[563.314966] (perf_pmu:2875) DEBUG: slept=500351000 perf=500390520
[563.855418] (perf_pmu:2875) INFO: busy=469236354 idle=0
[563.856149] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[563.856292] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[563.856378] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469236354.000...
...

Starting dynamic subtest: vcs0
[541.607237] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[541.607286] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[541.607346] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469411562.000...

Dynamic subtest vcs0 failed.
**** DEBUG ****
[538.052608] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[538.053022] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[540.053398] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[540.559885] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[540.567251] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[541.067775] (perf_pmu:2859) DEBUG: slept=500343000 perf=500409312
[541.606661] (perf_pmu:2859) INFO: busy=469411562 idle=0
[541.607237] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[541.607286] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[541.607346] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469411562.000...
...