Added diagnostics and random sleeps to understand AppRTC heisenbug better.

chrome/browser/media/chrome_webrtc_apprtc_browsertest.cc

 // Copyright 2013 The Chromium Authors. All rights reserved.
 // Use of this source code is governed by a BSD-style license that can be
 // found in the LICENSE file.
 
 #include "base/command_line.h"
 #include "base/files/file_enumerator.h"
 #include "base/path_service.h"
 #include "base/process/launch.h"
 #include "base/rand_util.h"
 #include "base/strings/stringprintf.h"
+#include "base/timer/elapsed_timer.h"
 #include "base/win/windows_version.h"
 #include "chrome/browser/browser_process.h"
 #include "chrome/browser/media/webrtc_browsertest_base.h"
 #include "chrome/browser/media/webrtc_browsertest_common.h"
 #include "chrome/browser/ui/browser.h"
 #include "chrome/browser/ui/browser_tabstrip.h"
 #include "chrome/browser/ui/tabs/tab_strip_model.h"
 #include "chrome/common/chrome_switches.h"
 #include "chrome/test/base/ui_test_utils.h"
 #include "content/public/test/browser_test_utils.h"
@@ skipping 191 matching lines @@
     NOTREACHED();
     return false;
   }
 
  private:
   base::ProcessHandle dev_appserver_;
   base::ProcessHandle firefox_;
 };
 
 IN_PROC_BROWSER_TEST_F(WebRtcApprtcBrowserTest, MANUAL_WorksOnApprtc) {
+  // TODO(phoglund): temporary diagnostics to debug the crbug.com/377383
+  // heisenbug.
+  base::ElapsedTimer timer;
+
   // TODO(mcasas): Remove Win version filtering when this bug gets fixed:
   // http://code.google.com/p/webrtc/issues/detail?id=2703
 #if defined(OS_WIN)
   if (base::win::GetVersion() < base::win::VERSION_VISTA)
     return;
 #endif
   DetectErrorsInJavaScript();
   ASSERT_TRUE(LaunchApprtcInstanceOnLocalhost());
   while (!LocalApprtcInstanceIsUp())
     VLOG(1) << "Waiting for AppRTC to come up...";
 
+  LOG(INFO) << "AppRTC server up; " << timer.Elapsed().InSeconds()
+            << " seconds elapsed.";
+
   GURL room_url = GURL(base::StringPrintf("localhost:9999?r=room_%d",
                                           base::RandInt(0, 65536)));
 
   chrome::AddTabAt(browser(), GURL(), -1, true);
   content::WebContents* left_tab = OpenPageAndAcceptUserMedia(room_url);
+
+  // TODO(phoglund): trying to tease out apprtc races.
+  test::SleepInJavascript(left_tab, 2000);
+
   chrome::AddTabAt(browser(), GURL(), -1, true);
   content::WebContents* right_tab = OpenPageAndAcceptUserMedia(room_url);
 
+  LOG(INFO) << "Pages opened; " << timer.Elapsed().InSeconds()
+      << " seconds elapsed.";
+
   ASSERT_TRUE(WaitForCallToComeUp(left_tab));
   ASSERT_TRUE(WaitForCallToComeUp(right_tab));
 
+  LOG(INFO) << "Call up; " << timer.Elapsed().InSeconds()
+            << " seconds elapsed.";
+
   ASSERT_TRUE(DetectRemoteVideoPlaying(left_tab));
   ASSERT_TRUE(DetectRemoteVideoPlaying(right_tab));
 
+  LOG(INFO) << "Remote video playing; " << timer.Elapsed().InSeconds()
+            << " seconds elapsed.";
+
   ASSERT_TRUE(HangUpApprtcCall(left_tab));
 
   ASSERT_TRUE(WaitForCallToHangUp(left_tab));
   ASSERT_TRUE(WaitForCallToHangUp(right_tab));
+
+  LOG(INFO) << "Hung up; " << timer.Elapsed().InSeconds()
+            << " seconds elapsed.";
+
+  // TODO(phoglund): trying to tease out apprtc races.
+  test::SleepInJavascript(left_tab, 2000);
+
+  LOG(INFO) << "Slept a bit; " << timer.Elapsed().InSeconds()
+            << " seconds elapsed.";
 }
 
 #if defined(OS_LINUX)
 #define MAYBE_MANUAL_FirefoxApprtcInteropTest MANUAL_FirefoxApprtcInteropTest
 #else
 // Not implemented yet on Windows and Mac.
 #define MAYBE_MANUAL_FirefoxApprtcInteropTest DISABLED_MANUAL_FirefoxApprtcInteropTest
 #endif
 
 IN_PROC_BROWSER_TEST_F(WebRtcApprtcBrowserTest,
@@ skipping 19 matching lines @@
                                           base::RandInt(0, 65536)));
   content::WebContents* chrome_tab = OpenPageAndAcceptUserMedia(room_url);
 
   ASSERT_TRUE(LaunchFirefoxWithUrl(room_url));
 
   ASSERT_TRUE(WaitForCallToComeUp(chrome_tab));
 
   // Ensure Firefox manages to send video our way.
   ASSERT_TRUE(DetectRemoteVideoPlaying(chrome_tab));
 }