explicitly log time spent in obtaining flashing permit

Bug: 27073663
Change-Id: I7cd4cb0efdaa31ca3fb2003d52b6d803a267d31a

1 files changed, 4 insertions, 0 deletions

diff --git a/src/com/android/tradefed/targetprep/DeviceFlashPreparer.java b/src/com/android/tradefed/targetprep/DeviceFlashPreparer.java
index 88fa64b10..cd03324cd 100644
--- a/src/com/android/tradefed/targetprep/DeviceFlashPreparer.java
+++ b/src/com/android/tradefed/targetprep/DeviceFlashPreparer.java
@@ -31,6 +31,7 @@ import com.android.tradefed.util.RunUtil;
 import java.util.ArrayList;
 import java.util.Collection;
 import java.util.concurrent.Semaphore;
+import java.util.concurrent.TimeUnit;
 
 /**
  * A {@link ITargetPreparer} that flashes an image on physical Android hardware.
@@ -212,7 +213,10 @@ public abstract class DeviceFlashPreparer implements ITargetCleaner {
             IDeviceFlasher flasher = createFlasher(device);
             // only surround fastboot related operations with flashing permit restriction
             try {
+                long start = System.currentTimeMillis();
                 takeFlashingPermit();
+                CLog.v("Flashing permit obtained after %ds",
+                        TimeUnit.MILLISECONDS.toSeconds((System.currentTimeMillis() - start)));
 
                 flasher.overrideDeviceOptions(device);
                 flasher.setUserDataFlashOption(mUserDataFlashOption);