added timing messages around paste to find slowness when pasting with large db

git-svn-id: svn://svn.code.sf.net/p/ditto-cp/code/trunk@673 595ec19a-5cb4-439b-94a8-42fb3063c22c

CP_Main.cpp

@@ -632,6 +632,8 @@ BOOL CCP_MainApp::EnterGroupID(long lID)
 	if(m_GroupID == lID)
 		return TRUE;
 
+	DWORD startTick = GetTickCount();
+
 	// if we are switching to the parent, focus on the previous group
 	if(m_GroupParentID == lID && m_GroupID > 0)
 		m_FocusID = m_GroupID;
@@ -671,6 +673,10 @@ BOOL CCP_MainApp::EnterGroupID(long lID)
 			QPasteWnd()->UpdateStatus(true);
 	}
 
+	DWORD endTick = GetTickCount();
+	if((endTick-startTick) > 150)
+		Log(StrF(_T("Paste Timing EnterParentId: %d"), endTick-startTick));
+
 	return bResult;
 }
 

ExternalWindowTracker.cpp

@@ -225,6 +225,8 @@ void ExternalWindowTracker::SendPaste(bool activateTarget)
 	CString csPasteString = g_Opt.GetPasteString(csPasteToApp);
 	DWORD delay = g_Opt.SendKeysDelay();
 
+	DWORD startTick = GetTickCount();
+
 	if(activateTarget)
 	{
 		ActivateTarget();
@@ -236,6 +238,10 @@ void ExternalWindowTracker::SendPaste(bool activateTarget)
 		theApp.PumpMessageEx();
 	}
 
+	DWORD endTick = GetTickCount();
+	if((endTick-startTick) > 150)
+		Log(StrF(_T("Paste Timing Send Paste around activate Target: %d"), endTick-startTick));
+
 	m_dittoHasFocus = false;
 	Log(StrF(_T("Sending paste to app %s key stroke: %s, SeDelay: %d"), csPasteToApp, csPasteString, delay));
 

ProcessPaste.cpp

@@ -106,6 +106,8 @@ void CProcessPaste::MarkAsPasted()
 
 UINT CProcessPaste::MarkAsPastedThread(LPVOID pParam)
 {
+	DWORD startTick = GetTickCount();
+
 	static CEvent UpdateTimeEvent(TRUE, TRUE, _T("Ditto_Update_Clip_Time"), NULL);
 	UpdateTimeEvent.ResetEvent();
 
@@ -119,12 +121,14 @@ UINT CProcessPaste::MarkAsPastedThread(LPVOID pParam)
 	}
 
 	BOOL bRet = FALSE;
+	int clipId = 0;
 
 	try
 	{
 		MarkAsPastedData* pData = (MarkAsPastedData*)pParam;
 		if(pData)
 		{
+			clipId = pData->clipId;
 			if(g_Opt.m_bUpdateTimeOnPaste)
 			{
 				try
@@ -179,6 +183,10 @@ UINT CProcessPaste::MarkAsPastedThread(LPVOID pParam)
 
 	Log(_T("End of MarkAsPastedThread"));
 
+	DWORD endTick = GetTickCount();
+	if((endTick-startTick) > 350)
+		Log(StrF(_T("Paste Timing MarkAsPastedThread: %d, ClipId: %d"), endTick-startTick, clipId));
+
 	UpdateTimeEvent.SetEvent();
 	return bRet;
 }

QPasteWnd.cpp

@@ -461,6 +461,7 @@ void CQPasteWnd::OnActivate(UINT nState, CWnd *pWndOther, BOOL bMinimized)
 BOOL CQPasteWnd::HideQPasteWindow(bool releaseFocus)
 {
     Log(_T("Start of HideQPasteWindow"));
+	DWORD startTick = GetTickCount();
 
     if(!theApp.m_bShowingQuickPaste)
     {
@@ -514,6 +515,10 @@ BOOL CQPasteWnd::HideQPasteWindow(bool releaseFocus)
         }
     }
 
+	DWORD endTick = GetTickCount();
+	if((endTick-startTick) > 150)
+		Log(StrF(_T("Paste Timing HideQPasteWindow: %d"), endTick-startTick));
+
     Log(StrF(_T("End of HideQPasteWindow, ItemCount: %d"), m_listItems.size()));
 
     return TRUE;
@@ -781,6 +786,8 @@ LRESULT CQPasteWnd::OnListEnd(WPARAM wParam, LPARAM lParam)
 
 LRESULT CQPasteWnd::OnReloadClipOrder(WPARAM wParam, LPARAM lParam)
 {
+	DWORD startTick = GetTickCount();
+
 	BOOL foundClip = FALSE;
 	int clipId = (int)wParam;
 
@@ -823,6 +830,10 @@ LRESULT CQPasteWnd::OnReloadClipOrder(WPARAM wParam, LPARAM lParam)
 		}
 	}
 
+	DWORD endTick = GetTickCount();
+	if((endTick-startTick) > 150)
+		Log(StrF(_T("Paste Timing OnReloadClipOrder: %d, ClipId: %d"), endTick-startTick, clipId));
+
 	return foundClip;
 }