[Android] Log instr test start & end.

build/android/pylib/local/device/local_device_instrumentation_test_run.py

Index: build/android/pylib/local/device/local_device_instrumentation_test_run.py
diff --git a/build/android/pylib/local/device/local_device_instrumentation_test_run.py b/build/android/pylib/local/device/local_device_instrumentation_test_run.py
index 5f5f409ab544bc5c707999c4e962648396a68389..a472f45fa32488e4baf66ba3a1816ab2bf2d3abc 100644
--- a/build/android/pylib/local/device/local_device_instrumentation_test_run.py
+++ b/build/android/pylib/local/device/local_device_instrumentation_test_run.py
@@ -255,12 +255,22 @@ class LocalDeviceInstrumentationTestRun(
         add=flags.add, remove=flags.remove)
 
     try:
+      device.RunShellCommand(
+          ['log', '-p', 'i', '-t',
+           self._test_instance.test_package.split('.')[-1],

[mikecase (-- gone --), 2016/10/01 01:04:38]

is this tag just the word "tests"?

Like "org.chromium.chrome.tests".split('.')[-1]

[jbudorick, 2016/10/04 21:54:25]

Clearly wasn't thinking this through all the way. Changing this, see below.

+           'START %s' % test_name],
+          check_return=True)

[mikecase (-- gone --), 2016/10/01 01:04:38]

So there will be two logs indicating the start of the test.

I tests: START testA                        // Logged here
I TestRunner: started: testA(a.b.c.MyTest)  // Logged by
InstrumentationTestRunner

Should we make it clearer that one of these logs come from host side and just
tag with "test_runner.py"

I test_runner.py: START testA               // Logged here
I TestRunner: started: testA(a.b.c.MyTest)  // Logged by
InstrumentationTestRunner

[jbudorick, 2016/10/04 21:54:25]

Correct.
Switched the tag to test_runner_py.

       time_ms = lambda: int(time.time() * 1e3)
       start_ms = time_ms()
       output = device.StartInstrumentation(
           target, raw=True, extras=extras, timeout=timeout, retries=0)
-      duration_ms = time_ms() - start_ms
     finally:
+      device.RunShellCommand(
+          ['log', '-p', 'i', '-t',
+           self._test_instance.test_package.split('.')[-1],
+           'END %s' % test_name],
+          check_return=True)
+      duration_ms = time_ms() - start_ms
       if flags:
         self._flag_changers[str(device)].Restore()
       if test_timeout_scale: