Starting dynamic subtest: vcs1
[131.094503] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[131.094584] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[131.094663] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470633125.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[127.540303] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[127.540703] (perf_pmu:2097) intel_allocator_reloc-DEBUG: Using reloc allocator
[129.540794] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[130.046238] (perf_pmu:2097) igt_dummyload-DEBUG: Test requirement passed: nen...
[130.053209] (perf_pmu:2097) DEBUG: Test requirement passed: !(fd < 0 && errno...
[130.553422] (perf_pmu:2097) DEBUG: slept=500045000 perf=500096967
[131.093710] (perf_pmu:2097) INFO: busy=470633125 idle=0
[131.094503] (perf_pmu:2097) CRITICAL: Test assertion failure function busy_do...
[131.094584] (perf_pmu:2097) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[131.094663] (perf_pmu:2097) CRITICAL: 'val' != 'ts[1] - ts[0]' (470633125.000...
...

Starting dynamic subtest: vcs1
[514.205436] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[514.205491] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[514.205554] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470351614.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[510.649474] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[510.649876] (perf_pmu:2232) intel_allocator_reloc-DEBUG: Using reloc allocator
[512.650268] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[513.156970] (perf_pmu:2232) igt_dummyload-DEBUG: Test requirement passed: nen...
[513.164891] (perf_pmu:2232) DEBUG: Test requirement passed: !(fd < 0 && errno...
[513.665393] (perf_pmu:2232) DEBUG: slept=500332000 perf=500383365
[514.204747] (perf_pmu:2232) INFO: busy=470351614 idle=0
[514.205436] (perf_pmu:2232) CRITICAL: Test assertion failure function busy_do...
[514.205491] (perf_pmu:2232) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[514.205554] (perf_pmu:2232) CRITICAL: 'val' != 'ts[1] - ts[0]' (470351614.000...
...

Starting dynamic subtest: vcs1
[518.167999] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[518.168042] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[518.168090] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470979531.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[514.616600] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[514.616961] (perf_pmu:2221) intel_allocator_reloc-DEBUG: Using reloc allocator
[516.617450] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[517.121790] (perf_pmu:2221) igt_dummyload-DEBUG: Test requirement passed: nen...
[517.128021] (perf_pmu:2221) DEBUG: Test requirement passed: !(fd < 0 && errno...
[517.628554] (perf_pmu:2221) DEBUG: slept=500367000 perf=500417681
[518.167456] (perf_pmu:2221) INFO: busy=470979531 idle=0
[518.167999] (perf_pmu:2221) CRITICAL: Test assertion failure function busy_do...
[518.168042] (perf_pmu:2221) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[518.168090] (perf_pmu:2221) CRITICAL: 'val' != 'ts[1] - ts[0]' (470979531.000...
...

Starting dynamic subtest: vcs1
[516.626196] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[516.626268] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[516.626411] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470419062.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[513.071316] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[513.071690] (perf_pmu:2235) intel_allocator_reloc-DEBUG: Using reloc allocator
[515.071985] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[515.579045] (perf_pmu:2235) igt_dummyload-DEBUG: Test requirement passed: nen...
[515.586147] (perf_pmu:2235) DEBUG: Test requirement passed: !(fd < 0 && errno...
[516.086483] (perf_pmu:2235) DEBUG: slept=500122000 perf=500213356
[516.625484] (perf_pmu:2235) INFO: busy=470419062 idle=0
[516.626196] (perf_pmu:2235) CRITICAL: Test assertion failure function busy_do...
[516.626268] (perf_pmu:2235) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[516.626411] (perf_pmu:2235) CRITICAL: 'val' != 'ts[1] - ts[0]' (470419062.000...
...

Starting dynamic subtest: vcs1
[513.974055] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[513.974129] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[513.974202] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (470893594.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[510.419671] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[510.420075] (perf_pmu:2219) intel_allocator_reloc-DEBUG: Using reloc allocator
[512.420505] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[512.927183] (perf_pmu:2219) igt_dummyload-DEBUG: Test requirement passed: nen...
[512.934465] (perf_pmu:2219) DEBUG: Test requirement passed: !(fd < 0 && errno...
[513.434988] (perf_pmu:2219) DEBUG: slept=500366000 perf=500402377
[513.973405] (perf_pmu:2219) INFO: busy=470893594 idle=0
[513.974055] (perf_pmu:2219) CRITICAL: Test assertion failure function busy_do...
[513.974129] (perf_pmu:2219) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[513.974202] (perf_pmu:2219) CRITICAL: 'val' != 'ts[1] - ts[0]' (470893594.000...
...

Starting dynamic subtest: vcs1
[516.001306] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[516.001377] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[516.001454] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470359792.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[512.445726] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[512.446060] (perf_pmu:2228) intel_allocator_reloc-DEBUG: Using reloc allocator
[514.446399] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[514.953987] (perf_pmu:2228) igt_dummyload-DEBUG: Test requirement passed: nen...
[514.961434] (perf_pmu:2228) DEBUG: Test requirement passed: !(fd < 0 && errno...
[515.461949] (perf_pmu:2228) DEBUG: slept=500359000 perf=500397853
[516.000656] (perf_pmu:2228) INFO: busy=470359792 idle=0
[516.001306] (perf_pmu:2228) CRITICAL: Test assertion failure function busy_do...
[516.001377] (perf_pmu:2228) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[516.001454] (perf_pmu:2228) CRITICAL: 'val' != 'ts[1] - ts[0]' (470359792.000...
...

Starting dynamic subtest: vcs1
[301.395968] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[301.396045] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[301.396124] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470488907.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[297.840938] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[297.841281] (perf_pmu:2369) intel_allocator_reloc-DEBUG: Using reloc allocator
[299.841095] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[300.347515] (perf_pmu:2369) igt_dummyload-DEBUG: Test requirement passed: nen...
[300.356039] (perf_pmu:2369) DEBUG: Test requirement passed: !(fd < 0 && errno...
[300.856383] (perf_pmu:2369) DEBUG: slept=500181000 perf=500220441
[301.395202] (perf_pmu:2369) INFO: busy=470488907 idle=0
[301.395968] (perf_pmu:2369) CRITICAL: Test assertion failure function busy_do...
[301.396045] (perf_pmu:2369) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[301.396124] (perf_pmu:2369) CRITICAL: 'val' != 'ts[1] - ts[0]' (470488907.000...
...

Starting dynamic subtest: vcs1
[551.105221] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[551.105289] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[551.105364] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469180573.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[547.549674] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[547.550037] (perf_pmu:2855) intel_allocator_reloc-DEBUG: Using reloc allocator
[549.550394] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[550.057992] (perf_pmu:2855) igt_dummyload-DEBUG: Test requirement passed: nen...
[550.065030] (perf_pmu:2855) DEBUG: Test requirement passed: !(fd < 0 && errno...
[550.565544] (perf_pmu:2855) DEBUG: slept=500353000 perf=500402660
[551.104566] (perf_pmu:2855) INFO: busy=469180573 idle=0
[551.105221] (perf_pmu:2855) CRITICAL: Test assertion failure function busy_do...
[551.105289] (perf_pmu:2855) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[551.105364] (perf_pmu:2855) CRITICAL: 'val' != 'ts[1] - ts[0]' (469180573.000...
...

Starting dynamic subtest: vcs1
[549.592798] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[549.592910] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[549.592978] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469140520.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[546.037873] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[546.038241] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[548.038591] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[548.544420] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[548.552738] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[549.053213] (perf_pmu:2877) DEBUG: slept=500319000 perf=500355420
[549.592222] (perf_pmu:2877) INFO: busy=469140520 idle=0
[549.592798] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[549.592910] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[549.592978] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (469140520.000...
...

Starting dynamic subtest: vcs1
[548.909443] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[548.909523] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[548.909672] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (468668594.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[545.357325] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[545.357685] (perf_pmu:2869) intel_allocator_reloc-DEBUG: Using reloc allocator
[547.357728] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[547.863030] (perf_pmu:2869) igt_dummyload-DEBUG: Test requirement passed: nen...
[547.870005] (perf_pmu:2869) DEBUG: Test requirement passed: !(fd < 0 && errno...
[548.370384] (perf_pmu:2869) DEBUG: slept=500204000 perf=500256985
[548.908710] (perf_pmu:2869) INFO: busy=468668594 idle=0
[548.909443] (perf_pmu:2869) CRITICAL: Test assertion failure function busy_do...
[548.909523] (perf_pmu:2869) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[548.909672] (perf_pmu:2869) CRITICAL: 'val' != 'ts[1] - ts[0]' (468668594.000...
...

Starting dynamic subtest: vcs1
[545.045605] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[545.045657] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.045714] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469287812.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[541.490833] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[541.491186] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[543.491608] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[543.998598] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.007054] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[544.507504] (perf_pmu:2859) DEBUG: slept=500336000 perf=500377966
[545.044962] (perf_pmu:2859) INFO: busy=469287812 idle=0
[545.045605] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[545.045657] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.045714] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469287812.000...
...

Starting dynamic subtest: vcs1
[571.231178] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[571.231337] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[571.231426] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (468960209.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[567.679508] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[567.679869] (perf_pmu:2877) intel_allocator_reloc-DEBUG: Using reloc allocator
[569.679864] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[570.186245] (perf_pmu:2877) igt_dummyload-DEBUG: Test requirement passed: nen...
[570.193656] (perf_pmu:2877) DEBUG: Test requirement passed: !(fd < 0 && errno...
[570.693791] (perf_pmu:2877) DEBUG: slept=500057000 perf=500095855
[571.230477] (perf_pmu:2877) INFO: busy=468960209 idle=0
[571.231178] (perf_pmu:2877) CRITICAL: Test assertion failure function busy_do...
[571.231337] (perf_pmu:2877) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[571.231426] (perf_pmu:2877) CRITICAL: 'val' != 'ts[1] - ts[0]' (468960209.000...
...

Starting dynamic subtest: vcs1
[548.362545] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[548.362614] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[548.362687] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469504791.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[544.808582] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[544.809009] (perf_pmu:2876) intel_allocator_reloc-DEBUG: Using reloc allocator
[546.809409] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[547.314667] (perf_pmu:2876) igt_dummyload-DEBUG: Test requirement passed: nen...
[547.322930] (perf_pmu:2876) DEBUG: Test requirement passed: !(fd < 0 && errno...
[547.823452] (perf_pmu:2876) DEBUG: slept=500355000 perf=500406523
[548.361872] (perf_pmu:2876) INFO: busy=469504791 idle=0
[548.362545] (perf_pmu:2876) CRITICAL: Test assertion failure function busy_do...
[548.362614] (perf_pmu:2876) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[548.362687] (perf_pmu:2876) CRITICAL: 'val' != 'ts[1] - ts[0]' (469504791.000...
...

Starting dynamic subtest: vcs1
[553.426987] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[553.427053] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[553.427115] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469173958.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[549.873788] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[549.874480] (perf_pmu:2867) intel_allocator_reloc-DEBUG: Using reloc allocator
[551.874454] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[552.380855] (perf_pmu:2867) igt_dummyload-DEBUG: Test requirement passed: nen...
[552.388143] (perf_pmu:2867) DEBUG: Test requirement passed: !(fd < 0 && errno...
[552.888514] (perf_pmu:2867) DEBUG: slept=500222000 perf=500272095
[553.426367] (perf_pmu:2867) INFO: busy=469173958 idle=0
[553.426987] (perf_pmu:2867) CRITICAL: Test assertion failure function busy_do...
[553.427053] (perf_pmu:2867) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[553.427115] (perf_pmu:2867) CRITICAL: 'val' != 'ts[1] - ts[0]' (469173958.000...
...

Starting dynamic subtest: vcs1
[567.435720] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[567.435792] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[567.435871] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469083229.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[563.880784] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[563.881145] (perf_pmu:2875) intel_allocator_reloc-DEBUG: Using reloc allocator
[565.881542] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[566.388222] (perf_pmu:2875) igt_dummyload-DEBUG: Test requirement passed: nen...
[566.395752] (perf_pmu:2875) DEBUG: Test requirement passed: !(fd < 0 && errno...
[566.896275] (perf_pmu:2875) DEBUG: slept=500350000 perf=500402282
[567.434957] (perf_pmu:2875) INFO: busy=469083229 idle=0
[567.435720] (perf_pmu:2875) CRITICAL: Test assertion failure function busy_do...
[567.435792] (perf_pmu:2875) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[567.435871] (perf_pmu:2875) CRITICAL: 'val' != 'ts[1] - ts[0]' (469083229.000...
...

Starting dynamic subtest: vcs1
[545.175720] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[545.175771] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.175828] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469317500.000...

Dynamic subtest vcs1 failed.
**** DEBUG ****
[541.619881] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[541.620215] (perf_pmu:2859) intel_allocator_reloc-DEBUG: Using reloc allocator
[543.620489] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.127287] (perf_pmu:2859) igt_dummyload-DEBUG: Test requirement passed: nen...
[544.135705] (perf_pmu:2859) DEBUG: Test requirement passed: !(fd < 0 && errno...
[544.636214] (perf_pmu:2859) DEBUG: slept=500346000 perf=500396908
[545.175046] (perf_pmu:2859) INFO: busy=469317500 idle=0
[545.175720] (perf_pmu:2859) CRITICAL: Test assertion failure function busy_do...
[545.175771] (perf_pmu:2859) CRITICAL: Failed assertion: (double)(val) <= (1.0...
[545.175828] (perf_pmu:2859) CRITICAL: 'val' != 'ts[1] - ts[0]' (469317500.000...
...