SPY-157: Fix auth logging times.

Motivation
----------
In the previous commit which added logging, it measured nanoseconds and printed
milliseconds. This also screwed up the debug/warn metric.

Modifications
-------------
Correctly convert nanoseconds to milliseconds.

Result
------
Timings are now properly warned and formatted.

Change-Id: Id174a49e03e052f9e32c98cc32aa7ad0b82bbb4d
Reviewed-on: http://review.couchbase.org/34936
Tested-by: Michael Nitschinger <michael.nitschinger@couchbase.com>
Reviewed-by: Matt Ingenthron <matt@couchbase.com>

Author: daschl

src/main/java/net/spy/memcached/auth/AuthThread.java

@@ -24,6 +24,7 @@
 package net.spy.memcached.auth;
 
 import java.util.concurrent.CountDownLatch;
+import java.util.concurrent.TimeUnit;
 import java.util.concurrent.atomic.AtomicBoolean;
 import java.util.concurrent.atomic.AtomicReference;
 
@@ -131,7 +132,8 @@ public void run() {
     } else {
       supportedMechs = authDescriptor.getMechs();
     }
-    long mechsDiff = System.nanoTime() - mechsStart;
+    long mechsDiff = TimeUnit.NANOSECONDS.toMillis(System.nanoTime()
+      - mechsStart);
     String msg = String.format("SASL List Mechanisms took %dms on %s",
       mechsDiff, node.toString());
     Level level = mechsDiff
@@ -186,7 +188,8 @@ public void complete() {
         }
         done.set(true); // If we were interrupted, tear down.
       } finally {
-        long stepDiff = System.nanoTime() - stepStart;
+        long stepDiff = TimeUnit.NANOSECONDS.toMillis(System.nanoTime()
+          - stepStart);
         msg = String.format("SASL Step took %dms on %s",
           stepDiff, node.toString());
         level = mechsDiff
@@ -204,7 +207,8 @@ public void complete() {
       }
     }
 
-    long totalDiff = System.nanoTime() - totalStart;
+    long totalDiff = TimeUnit.NANOSECONDS.toMillis(System.nanoTime()
+      - totalStart);
     msg = String.format("SASL Auth took %dms on %s",
       totalDiff, node.toString());
     level = mechsDiff >= AUTH_TOTAL_THRESHOLD ? Level.WARN : Level.DEBUG;