• Facebook
  • RSS Feed
  • Instagram
  • LinkedIn
  • Twitter
Aug 272010
 

An area I visit regular is OWA 2007 and the Enterprise Vault add-in for it.  One thing that I’ve looked at recently is “delays” in the retrieval process.  Here is the outcome of the testing that I did recently on some quite old VMWare machines :

 

The first thing that we know causes a slow down is when the IIS App pool has to be started.  It simply takes a little time to start these processes up.  This is largely out of our control, and when these do need to be started up we see the following :

19/08/2010 21:38:05 [1988,15] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:38:05 [1988,15] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:38:05 [1988,15] [EVServerRequest::CreateRequest] Sending request to: http://evserver.ev.local/EVAnon/restoreo2k.asp? etcetcetc

19/08/2010 21:38:05 [1988,15] [EVServerRequest::CreateRequest] Request timeout (milliseconds): 30000

19/08/2010 21:38:05 [1988,15] [EVServerRequest::AddHeader] Set header: EV-OWA-2007-Extensions-Version=8.0.4.0

19/08/2010 21:38:05 [1988,15] [EVServerRequest::CreateRequest] Making request for user: EVex2k7testuser1

19/08/2010 21:38:05 [1988,15] [EVServerRequest::AddHeader] Set header: X-EVOWA-User-Encoded=450056005C006500780032006B003700740065007300740075007300650072003100

19/08/2010 21:38:36 [1988,15] [RestoreRequest::Send] Exception sending request to restore item: System.Net.WebException: The operation has timed out

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.EVServerRequests.RestoreRequest.Send()

19/08/2010 21:38:36 [1988,15] [RequestProcessor::RestoreAndActOnItem] Item not restored

 

See the 30 seconds gap..  that’s the default timeout

This can be increased with a web.config setting :

EnterpriseVault_HttpWebRequestTimeout

eg

<add key="EnterpriseVault_HttpWebRequestTimeout" value="125000"/>

Now, you will see :

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:52:27 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:52:27 [1988,1] [EVServerRequest::CreateRequest] Sending request to: http://evserver.ev.local/EVAnon/restoreo2k.asp? etc etc

19/08/2010 21:52:27 [1988,1] [EVServerRequest::CreateRequest] Request timeout (milliseconds): 125000

19/08/2010 21:52:27 [1988,1] [EVServerRequest::AddHeader] Set header: EV-OWA-2007-Extensions-Version=8.0.4.0

19/08/2010 21:52:27 [1988,1] [EVServerRequest::CreateRequest] Making request for user: EVex2k7testuser1

19/08/2010 21:52:27 [1988,1] [EVServerRequest::AddHeader] Set header: X-EVOWA-User-Encoded=450056005C006500780032006B003700740065007300740075007300650072003100

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders] Response received from EV server:

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]   Status: 201 Basket Restoring

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]   Headers:

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Content-Length: 0

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Cache-Control: private

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Content-Type: text/html

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Date: Thu, 19 Aug 2010 20:54:05 GMT

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Set-Cookie: ASPSESSIONIDQAQBCSBT=CFEHMMMCEIPEBDIAOAPBMDGF; path=/

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     Server: Microsoft-IIS/6.0

19/08/2010 21:54:03 [1988,1] [EVServerRequest::LogResponseHeaders]     X-Powered-By: ASP.NET

19/08/2010 21:54:03 [1988,1] [RestoreRequest::Send] Item is being restored

19/08/2010 21:54:03 [1988,1] [RequestProcessor::RestoreAndActOnItem] Item is being restored

19/08/2010 21:54:03 [1988,1] [RequestProcessor::RestoreAndActOnItem] RestoreWaitMaxIterations: 30

19/08/2010 21:54:03 [1988,1] [RequestProcessor::RestoreAndActOnItem] RestoreWaitSleepTime: 2000

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:54:03 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:54:05 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:54:07 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:54:09 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.EML

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Creating XmlDocument

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Loading XmlDocument

19/08/2010 21:54:11 [1988,1] [WebDAVRequest::Send] Loaded XmlDocument: <?xml version="1.0"?><a:multistatus xmlns:b="urn:uuid:c2f41010-65b3-11d1-a29f-00aa00c14882/" xmlns:c="xml:" xmlns:d="http://schemas.microsoft.com/mapi/proptag/" xmlns:e="http://schemas.microsoft.com/exchange/" xmlns:a="DAV:"><a:response><a:href>https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted%20Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246374.201008191851000000.Z.C155256E1DA580BE10A2E83ECC9CAC21.eml</a:href><a:propstat><a:status>HTTP/1.1 200 OK</a:status><a:prop><d:x0FFF0102 b:dt="bin.base64">AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8C0AAA==

</d:x0FFF0102><e:outlookmessageclass>IPM.Note</e:outlookmessageclass></a:prop></a:propstat></a:response></a:multistatus>

19/08/2010 21:54:11 [1988,1] [WebDAVHelpers::GetRestoredItemId] Raw ID: AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8C0AAA==

19/08/2010 21:54:11 [1988,1] [WebDAVHelpers::GetRestoredItemId] Message class: IPM.Note

19/08/2010 21:54:11 [1988,1] [RequestProcessor::RestoreAndActOnItem] Item has been restored

 

You see the basket starts restoring the item when the IIS App pools have started up.  We then check every 2 seconds to see if we can open the item from the deleted items folder, and when we can, we’re in.

There is another web.config setting to control the number of iterations and the time between :

EnterpriseVault_RestoreWaitMaxIterations     30

EnterpriseVault_RestoreWaitSleepTime          2000

 

Retrieval of the next archived item :

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:18 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:58:18 [1988,1] [EVServerRequest::CreateRequest] Sending request to: http://evserver.ev.local/EVAnon/restoreo2k.asp etc etc

19/08/2010 21:58:18 [1988,1] [EVServerRequest::CreateRequest] Request timeout (milliseconds): 125000

19/08/2010 21:58:18 [1988,1] [EVServerRequest::AddHeader] Set header: EV-OWA-2007-Extensions-Version=8.0.4.0

19/08/2010 21:58:18 [1988,1] [EVServerRequest::CreateRequest] Making request for user: EVex2k7testuser1

19/08/2010 21:58:18 [1988,1] [EVServerRequest::AddHeader] Set header: X-EVOWA-User-Encoded=450056005C006500780032006B003700740065007300740075007300650072003100

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders] Response received from EV server:

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]   Status: 201 Basket Restoring

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]   Headers:

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Content-Length: 0

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Cache-Control: private

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Content-Type: text/html

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Date: Thu, 19 Aug 2010 20:58:24 GMT

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Set-Cookie: ASPSESSIONIDQAQBCSBT=DFEHMMMCLMFEGJANPNPMHEKO; path=/

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     Server: Microsoft-IIS/6.0

19/08/2010 21:58:21 [1988,1] [EVServerRequest::LogResponseHeaders]     X-Powered-By: ASP.NET

19/08/2010 21:58:21 [1988,1] [RestoreRequest::Send] Item is being restored

19/08/2010 21:58:21 [1988,1] [RequestProcessor::RestoreAndActOnItem] Item is being restored

19/08/2010 21:58:21 [1988,1] [RequestProcessor::RestoreAndActOnItem] RestoreWaitMaxIterations: 30

19/08/2010 21:58:21 [1988,1] [RequestProcessor::RestoreAndActOnItem] RestoreWaitSleepTime: 2000

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:21 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:22 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:24 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:26 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:28 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:29 [1988,1] [WebDAVRequest::Send] Exception sending WebDAV request: System.Net.WebException: The remote server returned an error: (404) Not Found.

   at System.Net.HttpWebRequest.GetResponse()

   at Symantec.EnterpriseVault.Owa.ExchangeStoreAccess.WebDAVRequest.Send()

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.EML

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Creating XmlDocument

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Loading XmlDocument

19/08/2010 21:58:31 [1988,1] [WebDAVRequest::Send] Loaded XmlDocument: <?xml version="1.0"?><a:multistatus xmlns:b="urn:uuid:c2f41010-65b3-11d1-a29f-00aa00c14882/" xmlns:c="xml:" xmlns:d="http://schemas.microsoft.com/mapi/proptag/" xmlns:e="http://schemas.microsoft.com/exchange/" xmlns:a="DAV:"><a:response><a:href>https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted%20Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192245971.201008191849530000.Z.C1552F8972377C6C1D314AF745DF9D81.eml</a:href><a:propstat><a:status>HTTP/1.1 200 OK</a:status><a:prop><d:x0FFF0102 b:dt="bin.base64">AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8C4AAA==</d:x0FFF0102>

<e:outlookmessageclass>IPM.Note</e:outlookmessageclass></a:prop></a:propstat></a:response></a:multistatus>

19/08/2010 21:58:31 [1988,1] [WebDAVHelpers::GetRestoredItemId] Raw ID: AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8C4AAA==

19/08/2010 21:58:31 [1988,1] [WebDAVHelpers::GetRestoredItemId] Message class: IPM.Note

19/08/2010 21:58:31 [1988,1] [RequestProcessor::RestoreAndActOnItem] Item has been restored

Much shorter time.

Also remember that when you retrieve the same archived item another time, then you don’t get the delay, because it’s retrieved immediately from the deleted items folder:

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Request url: https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246385.201008191851040000.Z.C1552C39ECA35661EBCCE3CB4AB25EA1.EML

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Request method: PROPFIND

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Request timeout (milliseconds): 125000

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Adding FBA cookies

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Adding Depth header: 0

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Request body: <?xml version="1.0"?><d:propfind xmlns:d="DAV:" ><d:prop><mapi:x0FFF0102 xmlns:mapi="http://schemas.microsoft.com/mapi/proptag/" /><exch:outlookmessageclass xmlns:exch="http://schemas.microsoft.com/exchange/" /></d:prop></d:propfind>

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Creating XmlDocument

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Loading XmlDocument

19/08/2010 22:00:50 [1988,1] [WebDAVRequest::Send] Loaded XmlDocument: <?xml version="1.0"?><a:multistatus xmlns:b="urn:uuid:c2f41010-65b3-11d1-a29f-00aa00c14882/" xmlns:c="xml:" xmlns:d="http://schemas.microsoft.com/mapi/proptag/" xmlns:e="http://schemas.microsoft.com/exchange/" xmlns:a="DAV:"><a:response><a:href>https://localhost/exchange/ex2k7testuser1@EV.Local/Deleted%20Items/11536637D980A4E44945BFFA72F2311581110000evsite!201008192246385.201008191851040000.Z.C1552C39ECA35661EBCCE3CB4AB25EA1.eml</a:href><a:propstat><a:status>HTTP/1.1 200 OK</a:status><a:prop><d:x0FFF0102 b:dt="bin.base64">AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8CoAAA==

</d:x0FFF0102><e:outlookmessageclass>IPM.Note</e:outlookmessageclass></a:prop></a:propstat></a:response></a:multistatus>

19/08/2010 22:00:50 [1988,1] [WebDAVHelpers::GetRestoredItemId] Raw ID: AAAAANBwSzwmBoBNoOoyNyDJIUgHAE7B8dF8XPpGpZLOs8xBUx8ArZkXspEAAE7B8dF8XPpGpZLOs8xBUx8ER1dt8CoAAA==

19/08/2010 22:00:50 [1988,1] [WebDAVHelpers::GetRestoredItemId] Message class: IPM.Note

19/08/2010 22:00:50 [1988,1] [RequestProcessor::RestoreAndActOnItem] Item already restored

If you enjoyed this post, please consider leaving a comment or subscribing to the RSS feed to have future articles delivered to your feed reader.

 Leave a Reply

You may use these HTML tags and attributes: <a href="" title=""> <abbr title=""> <acronym title=""> <b> <blockquote cite=""> <cite> <code> <del datetime=""> <em> <i> <q cite=""> <s> <strike> <strong>

(required)

(required)