uniform output

spec/codecs/json.rb

@@ -34,7 +34,7 @@ describe LogStash::Codecs::JSON do
       end
       duration = Time.now - start
       insist { count } == iterations
-      puts "codecs/json speed: #{iterations/duration}/sec"
+      puts "codecs/json rate: #{"%02.0f/sec" % (iterations / duration)}, elapsed: #{duration}s"
     end
 
     context "processing plain text" do

spec/event.rb

@@ -107,10 +107,12 @@ describe LogStash::Event do
     end
 
     it "should be fast?", :performance => true do
+      count = 100000
       2.times do
         start = Time.now
-        100000.times { subject["[j][k1]"] }
-        puts "Duration: #{Time.now - start}"
+        count.times { subject["[j][k1]"] }
+        duration = Time.now - start
+        puts "event #[] rate: #{"%02.0f/sec" % (count / duration)}, elapsed: #{duration}s"
       end
     end
   end
@@ -192,7 +194,7 @@ describe LogStash::Event do
       end
       duration = Time.now - start
     end
-    puts "event @timestamp parse rate: #{count / duration}/sec"
+    puts "event @timestamp parse rate: #{"%02.0f/sec" % (count / duration)}, elapsed: #{duration}s"
   end
 
   context "acceptable @timestamp formats" do

spec/filters/date_performance.rb

@@ -24,7 +24,7 @@ describe LogStash::Filters::Date, :if => RUBY_ENGINE == "jruby" do
         end
         duration = Time.now - start
       end
-      puts "date parse rate: #{event_count / duration}"
+      puts "filters/date parse rate: #{"%02.0f/sec" % (event_count / duration)}, elapsed: #{duration}s"
       insist { duration } < max_duration
     end
   end

spec/filters/grok.rb

@@ -450,7 +450,7 @@ describe LogStash::Filters::Grok do
       start = Time.now
       agent do
         duration = (Time.now - start)
-        puts "grok parse rate: #{"%02.0f/sec" % (event_count / duration)}, elapsed: #{duration}s"
+        puts "filters/grok parse rate: #{"%02.0f/sec" % (event_count / duration)}, elapsed: #{duration}s"
         insist { duration } < max_duration
       end
     end

spec/filters/kv.rb

@@ -125,7 +125,7 @@ describe LogStash::Filters::KV do
     start = Time.now
     agent do
       duration = (Time.now - start)
-      puts "kv rate: #{"%02.0f/sec" % (count / duration)}, elapsed: #{duration}s"
+      puts "filters/kv rate: #{"%02.0f/sec" % (count / duration)}, elapsed: #{duration}s"
     end
   end
 

spec/inputs/generator.rb

@@ -23,7 +23,7 @@ describe "inputs/generator", :performance => true do
         insist { event["sequence"] } == i
       end
       duration = Time.now - start
-      puts "Generator Rate: #{"%02.0f/sec" % (event_count / duration)}, Elapsed: #{duration}s"
+      puts "inputs/generator rate: #{"%02.0f/sec" % (event_count / duration)}, elapsed: #{duration}s"
       pipeline.shutdown
     end # input
   end

spec/speed.rb

@@ -17,6 +17,6 @@ describe "speed tests", :performance => true do
   start = Time.now
   agent do
     duration = (Time.now - start)
-    puts "Speed Rate: #{"%02.0f/sec" % (count / duration)}, Elapsed: #{duration}s"
+    puts "speed rate: #{"%02.0f/sec" % (count / duration)}, elapsed: #{duration}s"
   end
 end