Add extra tracing to Debug_InterruptIsolate test.

runtime/vm/json_stream.cc

 // Copyright (c) 2013, the Dart project authors.  Please see the AUTHORS file
 // for details. All rights reserved. Use of this source code is governed by a
 // BSD-style license that can be found in the LICENSE file.
 
 #include "platform/assert.h"
 
 #include "vm/dart_entry.h"
 #include "vm/debugger.h"
 #include "vm/json_stream.h"
 #include "vm/message.h"
@@ skipping 62 matching lines @@
       param_values_native[i] =
           zone->MakeCopyOfString(string_iterator.ToCString());
     }
     SetParams(param_keys_native, param_values_native, param_keys.Length());
   }
   if (FLAG_trace_service) {
     Isolate* isolate = Isolate::Current();
     ASSERT(isolate != NULL);
     const char* isolate_name = isolate->name();
     setup_time_micros_ = OS::GetCurrentTimeMicros();
-    int64_t timestamp = setup_time_micros_ - Dart::vm_isolate()->start_time();
-    OS::Print("[@%" Pd64 "] Isolate %s processing service request %s\n",
-              timestamp, isolate_name, method_);
+    OS::Print("[+%" Pd64 "ms] Isolate %s processing service request %s\n",
+              Dart::timestamp(), isolate_name, method_);
   }
   buffer_.Printf("{\"jsonrpc\":\"2.0\", \"result\":");
 }
 
 
 void JSONStream::SetupError() {
   buffer_.Clear();
   buffer_.Printf("{\"jsonrpc\":\"2.0\", \"error\":");
 }
 
@@ skipping 100 matching lines @@
   uint8_t* data = NULL;
   MessageWriter writer(&data, &allocator, false);
   writer.WriteMessage(reply);
   bool result = PortMap::PostMessage(new Message(port, data,
                                                  writer.BytesWritten(),
                                                  Message::kNormalPriority));
   if (FLAG_trace_service) {
     Isolate* isolate = Isolate::Current();
     ASSERT(isolate != NULL);
     const char* isolate_name = isolate->name();
-    int64_t current_time = OS::GetCurrentTimeMicros();
-    int64_t process_delta_micros = current_time - setup_time_micros_;
-    int64_t timestamp = current_time - Dart::vm_isolate()->start_time();
+    int64_t total_time = OS::GetCurrentTimeMicros() - setup_time_micros_;
     if (result) {
-      OS::Print("[@%" Pd64 "] Isolate %s processed service request %s "
+      OS::Print("[+%" Pd64 "ms] Isolate %s processed service request %s "
                 "(%" Pd64 "us)\n",
-                timestamp, isolate_name, method_, process_delta_micros);
+                Dart::timestamp(), isolate_name, method_, total_time);
     } else {
-      OS::Print("[@%" Pd64 "] Isolate %s processed service request %s "
+      OS::Print("[+%" Pd64 "ms] Isolate %s processed service request %s "
                 "(%" Pd64 "us) FAILED\n",
-                timestamp, isolate_name, method_, process_delta_micros);
+                Dart::timestamp(), isolate_name, method_, total_time);
     }
   }
 }
 
 
 const char* JSONStream::LookupParam(const char* key) const {
   for (int i = 0; i < num_params(); i++) {
     if (!strcmp(key, param_keys_[i])) {
       return param_values_[i];
     }
@@ skipping 630 matching lines @@
   ASSERT(len == len2);
   stream_->buffer_.AddChar('"');
   stream_->AddEscapedUTF8String(p);
   stream_->buffer_.AddChar('"');
   free(p);
 }
 
 #endif  // !PRODUCT
 
 }  // namespace dart