System.Data.SqlClient "Timeout expired" causing ASP.net web application to automatically restart.

From: Chris Langston (CLangston_at_horizon-boss.com)
Date: 08/12/04 

Date: Thu, 12 Aug 2004 17:00:07 -0400

I have a Web Server running IIS 5 or 6 on Windows 2K and Windows 2003 Server
that is experiencing strange shutdown problems. We are using ASP.NET v1.1
and our application is written in VB.NET

Here's the scenario:

1. .NET Windows Client on a remote machine makes a web service call to
update tables on a Web Server running SQL Server 2000.
2. The Update is updating about 1000 - 3000 records doing simple update
statements like "Update transactions Set UpdateTime = '08/12/2003 04:30:00
PM' "
3. The SqlClient code is receiving the error message below:

Timeout expired. The timeout period elapsed prior to completion of the
operation or the server is not responding.
The statement has been terminated. at
System.Data.SqlClient.SqlConnection.OnError(SqlException exception,
TdsParserState state)
   at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException
exception, TdsParserState state)
   at
System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObjec
t stateObj)
   at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior,
SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet
bulkCopyHandler, TdsParserStateObject stateObj)
   at System.Data.SqlClient.SqlCommand.InternalExecuteNonQuery(DbAsyncResult
result)
   at System.Data.SqlClient.SqlCommand.ExecuteNonQuery()
   at
HorizonBoss.Framework.DataAccess.HZSqlFactory.ExecuteNonQuery(HZRequest
request) in
C:\projects\dotnet\Fish.NET\HorizonBoss\Framework\DataAccess\HZSqlFactory.vb
:line 359
2004-08-12 16:36:13.70 [Thread 9] {Error}
HorizonBoss.MealPayLive.BusinessObjects.Patrons.Patron.UpdateLatestBalanceIn
formation( DataSet currentBalances ): Timeout expired. The timeout period
elapsed prior to completion of the operation or the server is not
responding.
The statement has been terminated.

4. I have all my code wrapped in Try/Catch blocks so I see where I catch the
Exception and write this out to a log file.
5. I have code in the Application_End section of the Global.asax and I see
where a different Thread ID comes along and ends my running application.

2004-08-12 16:35:33.70 [Thread 9] {Verbose}
HorizonBoss.MealPayLive.BusinessObjects.Patrons.Patron.UpdateLatestBalanceIn
formation( DataSet currentBalances ): SQL to execute: UPDATE
mplPatronBalanceUpdate SET DateBalanceLastUpdated = '8/12/2004 4:35:33 PM'
WHERE PatronID = '0857'
2004-08-12 16:36:13.70 [Thread 9] {Error}
HorizonBoss.Framework.DataAccess.HZSqlFactory.ExecuteNonQuery( HZRequest
request ): Timeout expired. The timeout period elapsed prior to completion
of the operation or the server is not responding.
The statement has been terminated. at
System.Data.SqlClient.SqlConnection.OnError(SqlException exception,
TdsParserState state)
   at System.Data.SqlClient.SqlInternalConnection.OnError(SqlException
exception, TdsParserState state)
   at
System.Data.SqlClient.TdsParser.ThrowExceptionAndWarning(TdsParserStateObjec
t stateObj)
   at System.Data.SqlClient.TdsParser.Run(RunBehavior runBehavior,
SqlCommand cmdHandler, SqlDataReader dataStream, BulkCopySimpleResultSet
bulkCopyHandler, TdsParserStateObject stateObj)
   at System.Data.SqlClient.SqlCommand.InternalExecuteNonQuery(DbAsyncResult
result)
   at System.Data.SqlClient.SqlCommand.ExecuteNonQuery()
   at
HorizonBoss.Framework.DataAccess.HZSqlFactory.ExecuteNonQuery(HZRequest
request) in
C:\projects\dotnet\Fish.NET\HorizonBoss\Framework\DataAccess\HZSqlFactory.vb
:line 359
2004-08-12 16:36:13.70 [Thread 9] {Error}
HorizonBoss.MealPayLive.BusinessObjects.Patrons.Patron.UpdateLatestBalanceIn
formation( DataSet currentBalances ): Timeout expired. The timeout period
elapsed prior to completion of the operation or the server is not
responding.
The statement has been terminated.
2004-08-12 16:36:14.09 [Thread 9] {Verbose}
HorizonBoss.MealPayLive.BusinessObjects.Patrons.Patron.UpdateLatestBalanceIn
formation( DataSet currentBalances ): Closing Database connection.
2004-08-12 16:36:14.09 [Thread 9] {Info}
HorizonBoss.MealPayLive.BusinessObjects.Patrons.Patron.UpdateLatestBalanceIn
formation( DataSet currentBalances ): FINISH:
2004-08-12 16:36:14.09 [Thread 9] {Info}
HorizonBoss.MealPayLive.WebServices.MealPayCentralWS.SendBalances( DataSet
currentBalances ): Successfully updated latest Real-Time Balance
Information.
2004-08-12 16:36:14.09 [Thread 9] {Info}
HorizonBoss.MealPayLive.WebServices.MealPayCentralWS.SendBalances( DataSet
currentBalances ): Finish:
2004-08-12 16:36:14.09 [Thread 9] {Verbose}
HorizonBoss.MealPayLive.WebServices.MealPayCentralWS.SendBalances( DataSet
currentBalances ): Results = True
2004-08-12 16:36:14.29 [Thread 6] {Warning}
HorizonBoss.MealPayLive.WebServices.Global.Application_End( Object sender,
EventArgs e ): Web Service application is ending.
2004-08-12 16:36:14.29 [Thread 6] {Warning}
HorizonBoss.MealPayLive.WebServices.Global.Application_End( Object sender,
EventArgs e ): No Errors caused the shutdown.

You can see from the Above log file where the Application_Ends on a
different Thread.

6. I also have code in the Application_Error section of the global.asax,
but this section of the code never gets hit.

7.. When the next Web Request comes in, I write out a History of all the
reasons why the Web Server last shutdown.

2004-08-12 16:14:30.40 [Thread 9] {Info}
HorizonBoss.MealPayLive.WebServices.Global.WriteProcessInformation(
ProcessInfo currentInfo ): ProcessID: 3836StartTime: 8/9/2004 1:51:25
PMStatus: 1ShutdownReason: 0RequestCount: 0PeakMemoryUsage: 54596Millseconds
the Worker process has been running: 152Minutes the Worker process has been
running: 23Hours the Worker process has been running: 2
2004-08-12 16:14:30.40 [Thread 9] {Verbose}
HorizonBoss.MealPayLive.WebServices.Global.WriteProcessInformationHistory(
): START:
2004-08-12 16:14:30.40 [Thread 9] {Info}
HorizonBoss.MealPayLive.WebServices.Global.WriteProcessInformation(
ProcessInfo currentInfo ): ProcessID: 3836StartTime: 8/9/2004 1:51:25
PMStatus: 1ShutdownReason: 0RequestCount: 38PeakMemoryUsage:
54596Millseconds the Worker process has been running: 167Minutes the Worker
process has been running: 23Hours the Worker process has been running: 2
2004-08-12 16:14:30.40 [Thread 9] {Verbose}
HorizonBoss.MealPayLive.WebServices.Global.WriteProcessInformationHistory(
): FINISH:

I write down the Shutdown Reason as 0. Which means unknown reason why the
web server shut itself down.

8. My database connection string looks as follows:

server=localhost;UID=MyUser;PWD=secret;database=DatabaseName;pooling=false;c
onnection reset=false;connection lifetime=5;min pool size=1;max pool
size=10;connection timeout=120

I'm completely stumped as to what can be terminating my web application. I
have verified that I'm not touching the \bin folder of the web application
as I know that this causing web servers to restart themselves.

Any help is greatly appreciated.

Relevant Pages
Loading