ASP.NET Request timeout when renaming files or folders in MOSS 2007
We have a MOSS 2007 Farm with 2 Web front end and 1 SQL and 1 admin/search server. In one particular Site collection we encountered odd timeout errors when users try to rename files or folders. The EditForm.aspx timouts after 300 seconds and gives an error page after a Edit Item / rename folder action. After that timeout the folder is renamed to the new name, so it seems to act properly. The W3WP.EXE process blocks furter communication and after the timout a ASP.NET error is generated on the server where the rename has taken place The event log displays a warning ASP.NET 2.0.50727.0 1309 event and the application pool is recylced after this event that the request has been aborted Same case in the web page after enabling extended error logging in web.config. Stack Trace: [HttpException (0x80004005): Request timed out.] Version Information: Microsoft .NET Framework Version:2.0.50727.3625; ASP.NET Version:2.0.50727.3634 No communication after the request has been initiated when running fiddler logging. I suspected it might be a bad masterpage. So I returned to the stock version. no change in behaviour. This is very bad, because users complains increases here. Running MOSS 2007 Standard SP2 No hotfixes on 2003 Server 32bits. Disabled all non stock features on this site collection, no luck. ULS logging mentions this during the rename. 04/04/2012 13:50:34.69 OWSTIMER.EXE (0x3064) 0x46D0 Windows SharePoint Services E-Mail 6871 Information The Incoming E-Mail service has completed a batch. The elapsed time was 00:00:00. The service processed 0 message(s) in total. 04/04/2012 13:51:17.74 mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected SQLException while Executing the cache invalidation simple timer job. We will not abort the job, and will retry in 60 seconds: System.Data.SqlClient.SqlException: Invalid object name 'AR_CacheCounters'. at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj) at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj) at System.Data.SqlClient.SqlDataReader.ConsumeMetaData() at System.Data.SqlClient.SqlDataReader.g... 04/04/2012 13:51:17.74* mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected ...et_MetaData() at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String resetOptionsString) at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, DbAsyncResult result) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior) at Micro... 04/04/2012 13:51:17.74* mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected ...soft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command, CommandBehavior behavior) at Microsoft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command) at Microsoft.Office.Server.ApplicationRegistry.Infrastructure.CacheInvalidationStateTracker.CheckStatus(Object stateInfo) 04/04/2012 13:51:34.57 OWSTIMER.EXE (0x3064) 0x2118 Windows SharePoint Services E-Mail 6871 Information The Incoming E-Mail service has completed a batch. The elapsed time was 00:00:00. The service processed 0 message(s) in total. 04/04/2012 13:52:17.74 mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected SQLException while Executing the cache invalidation simple timer job. We will not abort the job, and will retry in 60 seconds: System.Data.SqlClient.SqlException: Invalid object name 'AR_CacheCounters'. at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj) at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj) at System.Data.SqlClient.SqlDataReader.ConsumeMetaData() at System.Data.SqlClient.SqlDataReader.g... 04/04/2012 13:52:17.74* mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected ...et_MetaData() at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String resetOptionsString) at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, DbAsyncResult result) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior) at Micro... 04/04/2012 13:52:17.74* mssdmn.exe (0x441C) 0x4120 SharePoint Portal Server Business Data 7xzd Unexpected ...soft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command, CommandBehavior behavior) at Microsoft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command) at Microsoft.Office.Server.ApplicationRegistry.Infrastructure.CacheInvalidationStateTracker.CheckStatus(Object stateInfo) Planned update to MOSS 2007 SP3 to see if this solves matters, but I have a strong suspicion this won't solve this anomaly. All our other farms and site collections do behave properly, so it's site collection bound Any clues? Marcel
April 4th, 2012 9:46am

Hi Marcel, Thank you for your question. I am trying to involve someone familiar with this topic to further look at this issue. There might be some time delay. Appreciate your patience. Thank you for your understanding and support. Thanks, Entan Ming TechNet Subscriber Support in forum If you have any feedback on our support, please contacttnmff@microsoft.com. Entan Ming TechNet Community Support
Free Windows Admin Tool Kit Click here and download it now
April 5th, 2012 3:41am

Thanx Entan Ming, Yesterday evening we upgraded the farm to MOSS 2007 SP3 and Februari 2012 cumulative update. The slow response in that particular site collection is unfortunately still there, but it seems that the error page is not shown any more. This might be due to the fact that the number of users is low after 8 PM :-) It seems that after the rename the IIS sharepoint application pool freezes the site Here is a piece of the ULS after renaming a test folder: 04/04/2012 20:21:04.24 w3wp.exe (0x1240) 0x16A8 Windows SharePoint Services General 8kh7 High There is no file with URL 'Test1/test456' in this Web. 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC 484 880i High System.Data.SqlClient.SqlException: Invalid object name 'AR_CacheCounters'. at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj) at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj) at System.Data.SqlClient.SqlDataReader.ConsumeMetaData() at System.Data.SqlClient.SqlDataReader.get_MetaData() at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String res... 04/04/2012 20:21:04.73* mssdmn.exe (0x0184) 0x1AAC 484 880i High ...etOptionsString) at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, DbAsyncResult result) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior) at Microsoft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command, CommandBehavior behavior) 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC 484 880j High SqlError: 'Invalid object name 'AR_CacheCounters'.' Source: '.Net SqlClient Data Provider' Number: 208 State: 1 Class: 16 Procedure: 'proc_ar_GetCacheInvalidationCountersWithCount' LineNumber: 3 Server: 'ECNSQL' 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC 484 880k High at Microsoft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command) at Microsoft.Office.Server.ApplicationRegistry.Infrastructure.CacheInvalidationStateTracker.CheckStatus(Object stateInfo) at System.Threading._TimerCallback.TimerCallback_Context(Object state) at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state) at System.Threading._TimerCallback.PerformTimerCallback(Object state) 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC 484 880l High ConnectionString: 'Server=ECNSQL;Database=SharedServicesPortal_DB;Trusted_Connection=yes;App=Windows SharePoint Services;Timeout=15' ConnectionState: Closed ConnectionTimeout: 15 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC 484 880m High SqlCommand: 'proc_ar_GetCacheInvalidationCountersWithCount' CommandType: StoredProcedure CommandTimeout: 0 04/04/2012 20:21:04.73 mssdmn.exe (0x0184) 0x1AAC SharePoint Portal Server Business Data 7xzd Unexpected SQLException while Executing the cache invalidation simple timer job. We will not abort the job, and will retry in 60 seconds: System.Data.SqlClient.SqlException: Invalid object name 'AR_CacheCounters'. at System.Data.SqlClient.SqlConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException exception, Boolean breakConnection) at System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObject stateObj) at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior, SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet bulkCopyHandler, TdsParserStateObject stateObj) at System.Data.SqlClient.SqlDataReader.ConsumeMetaData() at System.Data.SqlClient.SqlDataReader.g... 04/04/2012 20:21:04.73* mssdmn.exe (0x0184) 0x1AAC SharePoint Portal Server Business Data 7xzd Unexpected ...et_MetaData() at System.Data.SqlClient.SqlCommand.FinishExecuteReader(SqlDataReader ds, RunBehavior runBehavior, String resetOptionsString) at System.Data.SqlClient.SqlCommand.RunExecuteReaderTds(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, Boolean async) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method, DbAsyncResult result) at System.Data.SqlClient.SqlCommand.RunExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior, String method) at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior) at Micro... 04/04/2012 20:21:04.73* mssdmn.exe (0x0184) 0x1AAC SharePoint Portal Server Business Data 7xzd Unexpected ...soft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command, CommandBehavior behavior) at Microsoft.Office.Server.Data.SqlSession.ExecuteReader(SqlCommand command) at Microsoft.Office.Server.ApplicationRegistry.Infrastructure.CacheInvalidationStateTracker.CheckStatus(Object stateInfo) ------- Also the gatherer/search logs something just after the rename folder. it seems if the search engine tries to do something to fix references to the renamed item. This site collection db is about 80Gb and the database is SQL 2005 x86. It has publishing features enabled. disabling that did not solve things.
April 5th, 2012 4:38am

I do not know if I completely understand exactly what is still happening at the moment. After applying the Febraurary 2011 CU, is it that the problem with renaming the files and folders no longer occurs, but you are instead experiencing some other slowness in the site collection? If so, what exactly is being done that produces performance decreases? However, if the problem is still occurring when renaming a file or folder, how are those files/folders being created and uploaded? If done manually, does the problem occur? Is this issue only confined to one site? Or is it confined to only one site collection? Does it occur on multiple web applications? Finally, have any of the affected libraries this occurs in been renamed? If so, were they renamed through Designer so that the URL of the library was changed as well?Regards, Jerad Plesuk Technical Support | SharePoint Technologies | Microsoft Corporation ----------------------------------------------------------------------------------------- This posting is provided "AS IS" with no warranties, and confers no rights
Free Windows Admin Tool Kit Click here and download it now
April 5th, 2012 5:10pm

The slowness is still there, is bound to one particular site collection and the timeout does still exist, but it seems to fit within the IIS timeout boundary, so the error page does not show up as often as before the SP3 and Feb CU update. If I use a test doclib in the toplevel site in the sitecollection and create a subfolder and try to rename the subfolder trough edit properties, the whole IIS process seems to freeze and nobody else can access their own stuff for a few minutes. Same issue when using explorer view. Other MOSS web applications/site collections such as mysites seems to work OK.
April 10th, 2012 7:02am

If you create a brand new document library in the site collection, does the issue occur there as well? If not, can you copy or move items/folders from one of the affected libraries to the new one and test for the problem in the new one again? Another option you may want to try is exporting the entire site and then importing it into a test web application to see if that resolves the problem. If so, you might want to consider moving the entire site collection as a way to resolve the issue as well.Regards, Jerad Plesuk Technical Support | SharePoint Technologies | Microsoft Corporation ----------------------------------------------------------------------------------------- This posting is provided "AS IS" with no warranties, and confers no rights
Free Windows Admin Tool Kit Click here and download it now
April 13th, 2012 5:00pm

Hi there, I have experienced similar time-outs when a user account was deleted from AD and re-created again, yet not introduced the changes in SharePoint and once the user was logging on the site collection, the whole web application was freezing. Just a thought, check if you have any old user accounts having permission on that folder. Cheers,
April 14th, 2012 4:22pm

We finally solved the matter with help from Microsoft After opening a case and sending a large ULS log to Microsoft for analysis, the problem seems to be SQL server related. The suggestion was that our SQL server not able to use Full RAM due to Disabled AWE settings. Tthis is a large site collection database (>80GB) so that made sense. Since we had installed a brand new SQL 2008 X64 cluster, we moved the sharepoint databases from the old SQL 2005 X86 to the new cluster instead of enabling AWE on the old limited address spaced 32 bits server. That brought the site collection back to life again. The increased performance was amazing. Case closed Thanks for the support
Free Windows Admin Tool Kit Click here and download it now
May 23rd, 2012 3:01am

This topic is archived. No further replies will be accepted.

Other recent topics Other recent topics