Track timings for job updates.

This gives us a bit more insight as to where time is spent when a
job is updated in hub. It breaks down into tracking the entire
update per event type, the notify method per event time and
propagation per event type, in the hope that this gives us a few
more clues as to where time is spent.

Author: roidrage

lib/travis/model/job.rb

@@ -85,7 +85,9 @@ def propagate(name, *args)
     if name == :cancel
       name = :cancel_job
     end
-    source.send(name, *args)
+    Metriks.timer("job.propagate.#{name}").time do
+      source.send(name, *args)
+    end
     true
   end
 

lib/travis/model/job/test.rb

@@ -90,8 +90,10 @@ def unknown?
     end
 
     def notify(event, *args)
-      event = :create if event == :reset
-      super
+      Metriks.timer("job.notify.#{event}").time do
+        event = :create if event == :reset
+        super
+      end
     end
 
     delegate :id, :content, :to => :log, :prefix => true, :allow_nil => true

lib/travis/services/update_job.rb

@@ -16,7 +16,9 @@ def run
           # the first one
           cancel_job_in_worker
         else
-          job.send(:"#{event}!", data.except(:id))
+          Metriks.timer("update_job.#{event}").time do
+            job.send(:"#{event}!", data.except(:id))
+          end
         end
       end
       instrument :run