Added more logging to debug a test which fails in the build bots.

This test times out regularly in the vista build bots and infrequently in others.
I added some logs earlier and making progress, so adding more logs to narrow down the issue.

BUG=51337
TEST=none

Review URL: http://codereview.chromium.org/3618008

git-svn-id: svn://svn.chromium.org/chrome/trunk/src@61642 0039d316-1c4b-4281-b951-d872f2087c98

2 files changed, 14 insertions, 2 deletions

diff --git a/chrome/browser/speech/speech_input_dispatcher_host.cc b/chrome/browser/speech/speech_input_dispatcher_host.cc
index 52409b1..7532e09 100644
--- a/chrome/browser/speech/speech_input_dispatcher_host.cc
+++ b/chrome/browser/speech/speech_input_dispatcher_host.cc
@@ -181,7 +181,7 @@ void SpeechInputDispatcherHost::SetRecognitionResult(int caller_id,
 }
 
 void SpeechInputDispatcherHost::DidCompleteRecording(int caller_id) {
-  LOG(INFO) << "SpeechInputDispatcherHost::DidCompleteRecording exit";
+  LOG(INFO) << "SpeechInputDispatcherHost::DidCompleteRecording enter";
   DCHECK(ChromeThread::CurrentlyOn(ChromeThread::IO));
   int caller_render_view_id = callers_->render_view_id(caller_id);
   int caller_request_id = callers_->request_id(caller_id);
@@ -193,7 +193,7 @@ void SpeechInputDispatcherHost::DidCompleteRecording(int caller_id) {
 }
 
 void SpeechInputDispatcherHost::DidCompleteRecognition(int caller_id) {
-  LOG(INFO) << "SpeechInputDispatcherHost::DidCompleteRecognition exit";
+  LOG(INFO) << "SpeechInputDispatcherHost::DidCompleteRecognition enter";
   DCHECK(ChromeThread::CurrentlyOn(ChromeThread::IO));
   int caller_render_view_id = callers_->render_view_id(caller_id);
   int caller_request_id = callers_->request_id(caller_id);
diff --git a/chrome/renderer/speech_input_dispatcher.cc b/chrome/renderer/speech_input_dispatcher.cc
index 54adfa8..88ea447 100644
--- a/chrome/renderer/speech_input_dispatcher.cc
+++ b/chrome/renderer/speech_input_dispatcher.cc
@@ -35,33 +35,45 @@ bool SpeechInputDispatcher::OnMessageReceived(const IPC::Message& message) {
 
 bool SpeechInputDispatcher::startRecognition(
     int request_id, const WebKit::WebRect& element_rect) {
+  LOG(INFO) << "SpeechInputDispatcher::startRecognition enter";
   gfx::Size scroll = render_view_->webview()->mainFrame()->scrollOffset();
   gfx::Rect rect = element_rect;
   rect.Offset(-scroll.width(), -scroll.height());
   render_view_->Send(new ViewHostMsg_SpeechInput_StartRecognition(
       render_view_->routing_id(), request_id, rect));
+  LOG(INFO) << "SpeechInputDispatcher::startRecognition exit";
   return true;
 }
 
 void SpeechInputDispatcher::cancelRecognition(int request_id) {
+  LOG(INFO) << "SpeechInputDispatcher::cancelRecognition enter";
   render_view_->Send(new ViewHostMsg_SpeechInput_CancelRecognition(
       render_view_->routing_id(), request_id));
+  LOG(INFO) << "SpeechInputDispatcher::cancelRecognition exit";
 }
 
 void SpeechInputDispatcher::stopRecording(int request_id) {
+  LOG(INFO) << "SpeechInputDispatcher::stopRecording enter";
   render_view_->Send(new ViewHostMsg_SpeechInput_StopRecording(
       render_view_->routing_id(), request_id));
+  LOG(INFO) << "SpeechInputDispatcher::stopRecording exit";
 }
 
 void SpeechInputDispatcher::OnSpeechRecognitionResult(
     int request_id, const string16& result) {
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecognitionResult enter";
   listener_->setRecognitionResult(request_id, result);
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecognitionResult exit";
 }
 
 void SpeechInputDispatcher::OnSpeechRecordingComplete(int request_id) {
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecordingComplete enter";
   listener_->didCompleteRecording(request_id);
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecordingComplete exit";
 }
 
 void SpeechInputDispatcher::OnSpeechRecognitionComplete(int request_id) {
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecognitionComplete enter";
   listener_->didCompleteRecognition(request_id);
+  LOG(INFO) << "SpeechInputDispatcher::OnSpeechRecognitionComplete exit";
 }