From 05447b67ffc9144ddeb9ddeb5de8ae460f48ebbe Mon Sep 17 00:00:00 2001
From: Aapo Talvensaari <aapo.talvensaari@gmail.com>
Date: Tue, 26 Apr 2016 14:19:03 +0300
Subject: [PATCH] Small fixes to microbenchmark.

---
 lib/resty/template/microbenchmark.lua | 42 ++++++++++++++++++++++-----
 1 file changed, 35 insertions(+), 7 deletions(-)

diff --git a/lib/resty/template/microbenchmark.lua b/lib/resty/template/microbenchmark.lua
index 7d89438..1160de3 100644
--- a/lib/resty/template/microbenchmark.lua
+++ b/lib/resty/template/microbenchmark.lua
@@ -6,7 +6,7 @@ if not ok then
 end
 
 local function run(iterations)
-    local total, print, parse, compile, iterations, clock, format = 0, ngx and ngx.say or print, template.parse, template.compile, iterations or 1000, os.clock, string.format
+    local gc, total, print, parse, compile, iterations, clock, format = collectgarbage, 0, ngx and ngx.say or print, template.parse, template.compile, iterations or 1000, os.clock, string.format
     local view = [[
     <ul>
     {% for _, v in ipairs(context) do %}
@@ -16,6 +16,9 @@ local function run(iterations)
 
     print(format("Running %d iterations in each test", iterations))
 
+    gc()
+    gc()
+
     local x = clock()
     for _ = 1, iterations do
         parse(view, true)
@@ -24,6 +27,9 @@ local function run(iterations)
     print(format("    Parsing Time: %.6f", z))
     total = total + z
 
+    gc()
+    gc()
+
     x = clock()
     for _ = 1, iterations do
         compile(view, nil, true)
@@ -35,18 +41,24 @@ local function run(iterations)
 
     compile(view, nil, true)
 
+    gc()
+    gc()
+
     x = clock()
     for _ = 1, iterations do
         compile(view, 1, true)
     end
     z = clock() - x
-    print(format("Compilation Time: %.6f (template cached)", z))
+    print(format("Compilation Time: %.6f (template, cached)", z))
     total = total + z
 
     local context = { "Emma", "James", "Nicholas", "Mary" }
 
     template.cache = {}
 
+    gc()
+    gc()
+
     x = clock()
     for _ = 1, iterations do
         compile(view, 1, true)(context)
@@ -59,12 +71,15 @@ local function run(iterations)
     template.cache = {}
     compile(view, 1, true)
 
+    gc()
+    gc()
+
     x = clock()
     for _ = 1, iterations do
         compile(view, 1, true)(context)
     end
     z = clock() - x
-    print(format("  Execution Time: %.6f (same template cached)", z))
+    print(format("  Execution Time: %.6f (same template, cached)", z))
     total = total + z
 
     template.cache = {}
@@ -74,6 +89,9 @@ local function run(iterations)
         views[i] = "<h1>Iteration " .. i .. "</h1>\n" .. view
     end
 
+    gc()
+    gc()
+
     x = clock()
     for i = 1, iterations do
         compile(views[i], i, true)(context)
@@ -82,21 +100,28 @@ local function run(iterations)
     print(format("  Execution Time: %.6f (different template)", z))
     total = total + z
 
+    gc()
+    gc()
+
     x = clock()
     for i = 1, iterations do
         compile(views[i], i, true)(context)
     end
     z = clock() - x
-    print(format("  Execution Time: %.6f (different template cached)", z))
+    print(format("  Execution Time: %.6f (different template, cached)", z))
     total = total + z
 
-    template.cache = {}
     local contexts = new_tab(iterations, 0)
 
     for i = 1, iterations do
         contexts[i] = {"Emma " .. i, "James " .. i, "Nicholas " .. i, "Mary " .. i }
     end
 
+    template.cache = {}
+
+    gc()
+    gc()
+
     x = clock()
     for i = 1, iterations do
         compile(views[i], i, true)(contexts[i])
@@ -105,12 +130,15 @@ local function run(iterations)
     print(format("  Execution Time: %.6f (different template, different context)", z))
     total = total + z
 
+    gc()
+    gc()
+
     x = clock()
-    for i = 1, 1000 do
+    for i = 1, iterations do
         compile(views[i], i, true)(contexts[i])
     end
     z = clock() - x
-    print(format("  Execution Time: %.6f (different template, different context cached)", z))
+    print(format("  Execution Time: %.6f (different template, different context, cached)", z))
     total = total + z
     print(format("      Total Time: %.6f", total))
 end