This is a migrated thread and some comments may be shown as answers.

Check Connection State vs Not Checking Connection State

7 Answers 225 Views
Development (API, general questions)
This is a migrated thread and some comments may be shown as answers.
This question is locked. New answers and comments are not allowed.
MarkInTexas
Top achievements
Rank 1
MarkInTexas asked on 14 Dec 2014, 05:56 PM
I ran into a problem with an application that is using Data Access where eventually it would crash to due "Out of Memory" exceptions (was taking 12-20 hours) to run out of memory.

Tracking down "Out of memory" exceptions can take awhile.
Anyway, long story short I eventually discovered the following Data Access issue or behavior that I do not understand and created a small test application to isolate the findings to report here.

This test application saves data via Data Access with two methods, one that checks the connection state and one that does not.

The method below that checks the connection appears to not release connection resources and over time will eventually cause the application to crash.
Now, I know the code below is not super efficient but the point was to demonstrate the problem by adding 1000-5000 objects to the database I can definitely see memory trending up in Just Trace (and lots of sql releated objects not GCd), the good news is adding one data object via Data Access also demonstrates the issue.

In my production application I removed the Connection State check and process memory remains stable even over long periods of time however it would be nice to know that ) the Data Context will not leak if any connection methods or properties are used b) and of course, please tell me the proper way if I am not doing things correctly.

I ran into a problem with an application that is using Data Access where eventually it would crash to due "Out of Memory" exceptions (was taking 12-20 hours) to run out of memory.

Tracking down "Out of memory" exceptions can take awhile.

Anyway, long story short I eventually discovered the following Data Access issue or behavior that I do not understand and created a small test application to isolate the findings to report here.

This test application saves data via Data Access with two methods, one that checks the connection state and one that does not.

The method below that checks the connection appears to not release
connection resources and over time will eventually cause the application
to crash.

Now, I know the code below is not super efficient but the point was to demonstrate the problem by adding 1000-5000 I can definitely see memory trending up in Just Trace but the good news is adding even one data object via Data Access also demonstrates the issue.

In my production application I removed the Connection State check and process memory remains stable even over long periods of time however it would be nice to know that a) the Data Context will not leak if any connection methods or properties are used b) and the proper way if I am not doing things correctly.

  1. Here are the two methods in the test application that add and save a small data object via Data Access

    This one works (no resource leaks)
private void ExecuteAddNewRecords()
   {
     for (int i = 0; i < this.NumberOfRecords; i++)
     {
       using (EntitiesModel context = new EntitiesModel())
       {
         context.Add(new MyEventLog
         {
           Message = DateTime.Now.ToString("MM/dd/yyyy HH:mm:ss.fff")
         });
         context.SaveChanges();
       }
     }
   }

Here is the one that appears to leak via connection related SQL objects
private void ExecuteAddNewRecordsWithConnectionCheck()
 {
   for (int i = 0; i < this.NumberOfRecords; i++)
   {
     using (EntitiesModel context = new EntitiesModel())
     {
       if (context.Connection.State == System.Data.ConnectionState.Open)
       {
         context.Add(new MyEventLog
         {
           Message = DateTime.Now.ToString("MM/dd/yyyy HH:mm:ss.fff")
         });
         context.SaveChanges();
       }
     }
   }
 }

I turned on logging for Data Access and also used Just Trace to confirm the results.

Data Access Log with No Check for connection state (there are 4 releases during the add/save process)
Telerik.OpenAccess Information: 385 : driver.rs.close
Telerik.OpenAccess Information: 386 : driver.con.commit           9
Telerik.OpenAccess Information: 387 : driver.pool.release         19 active=0/10 idle=2/10 con=-843401329
Telerik.OpenAccess Information: 388 : sm.begin                    19 optimistic

Data Access Log with Check for connection state (also notice that since there are previous releases missing on 464 - MultipleActiveResults was enabled).
(There are 0 release during add/save process)

Telerik.OpenAccess Information: 457 : driver.rs.close
Telerik.OpenAccess Information: 458 : driver.con.commit           12
Telerik.OpenAccess Information: 459 : sm.begin                    24 optimistic
Telerik.OpenAccess Information: 460 : driver.con.begin            12 ReadCommitted
Telerik.OpenAccess Information: 461 : driver.con.connect          13 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 462 : driver.con.createStat       13
Telerik.OpenAccess Information: 463 : driver.stat.executeNonQuery 13 SET LOCK_TIMEOUT 5000 []
Telerik.OpenAccess Information: 464 : driver.con.connect          14 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 465 : driver.con.createStat       14
Telerik.OpenAccess Information: 466 : driver.stat.executeNonQuery 14 SET LOCK_TIMEOUT 5000 []

In Just Trace I also confirmed these results and can see the memory growing over time and it does appear there are a number of SQL related objects retained in memory and GC will never free them.

My Questions and comments are:

  1. *First, I can post the test application if needed as well as the copies of the Telerik Data logs (actually I copied them below)
  2. What is the proper way to check the state of a connection and/or use any property method on the connection object, is there something I missed ?
  3. Below are the "Complete" Data logs for adding and saving one record to the database via both approaches.

Let me know if you require the test application of copies of the logs.

Thanks for your help and time,
Mark.

------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------

DATA ACCESS LOGS
No connection check (no leaks)  - Release calls are in bold (there are 4 of them)
Telerik.OpenAccess Information: 329 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 330 : sm.begin                    15 optimistic
Telerik.OpenAccess Information: 331 : sm.store                    15 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 332 : driver.pool.alloc           15 active=1/10 idle=1/10 con=-843401329
Telerik.OpenAccess Information: 333 : driver.con.begin            9 ReadCommitted
Telerik.OpenAccess Information: 334 : driver.con.createStat       9
Telerik.OpenAccess Information: 335 : driver.con.prepareCall      9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 336 : driver.stat.execQuery       9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:32:44.4164"]
Telerik.OpenAccess Information: 337 : driver.rs.close
Telerik.OpenAccess Information: 338 : driver.con.commit           9
Telerik.OpenAccess Information: 339 : driver.pool.release         15 active=0/10 idle=2/10 con=-843401329
Telerik.OpenAccess Information: 340 : sm.begin                    15 optimistic
Telerik.OpenAccess Information: 341 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 342 : sm.begin                    16 optimistic
Telerik.OpenAccess Information: 343 : sm.store                    16 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 344 : driver.pool.alloc           16 active=1/10 idle=1/10 con=-842352745
Telerik.OpenAccess Information: 345 : driver.con.begin            8 ReadCommitted
Telerik.OpenAccess Information: 346 : driver.con.createStat       8
Telerik.OpenAccess Information: 347 : driver.con.prepareCall      8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 348 : driver.stat.execQuery       8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:32:44.4294"]
Telerik.OpenAccess Information: 349 : driver.rs.close
Telerik.OpenAccess Information: 350 : driver.con.commit           8
Telerik.OpenAccess Information: 351 : driver.pool.release         16 active=0/10 idle=2/10 con=-842352745
Telerik.OpenAccess Information: 352 : sm.begin                    16 optimistic
Telerik.OpenAccess Information: 353 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 354 : sm.begin                    17 optimistic
Telerik.OpenAccess Information: 355 : sm.store                    17 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 356 : driver.pool.alloc           17 active=1/10 idle=1/10 con=-843401329
Telerik.OpenAccess Information: 357 : driver.con.begin            9 ReadCommitted
Telerik.OpenAccess Information: 358 : driver.con.createStat       9
Telerik.OpenAccess Information: 359 : driver.con.prepareCall      9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 360 : driver.stat.execQuery       9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:32:44.4407"]
Telerik.OpenAccess Information: 361 : driver.rs.close
Telerik.OpenAccess Information: 362 : driver.con.commit           9
Telerik.OpenAccess Information: 363 : driver.pool.release         17 active=0/10 idle=2/10 con=-843401329
Telerik.OpenAccess Information: 364 : sm.begin                    17 optimistic
Telerik.OpenAccess Information: 365 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 366 : sm.begin                    18 optimistic
Telerik.OpenAccess Information: 367 : sm.store                    18 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 368 : driver.pool.alloc           18 active=1/10 idle=1/10 con=-842352745
Telerik.OpenAccess Information: 369 : driver.con.begin            8 ReadCommitted
Telerik.OpenAccess Information: 370 : driver.con.createStat       8
Telerik.OpenAccess Information: 371 : driver.con.prepareCall      8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 372 : driver.stat.execQuery       8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:32:44.4497"]
Telerik.OpenAccess Information: 373 : driver.rs.close
Telerik.OpenAccess Information: 374 : driver.con.commit           8
Telerik.OpenAccess Information: 375 : driver.pool.release         18 active=0/10 idle=2/10 con=-842352745
Telerik.OpenAccess Information: 376 : sm.begin                    18 optimistic
Telerik.OpenAccess Information: 377 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 378 : sm.begin                    19 optimistic
Telerik.OpenAccess Information: 379 : sm.store                    19 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 380 : driver.pool.alloc           19 active=1/10 idle=1/10 con=-843401329
Telerik.OpenAccess Information: 381 : driver.con.begin            9 ReadCommitted
Telerik.OpenAccess Information: 382 : driver.con.createStat       9
Telerik.OpenAccess Information: 383 : driver.con.prepareCall      9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 384 : driver.stat.execQuery       9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:32:44.4595"]
Telerik.OpenAccess Information: 385 : driver.rs.close
Telerik.OpenAccess Information: 386 : driver.con.commit           9
Telerik.OpenAccess Information: 387 : driver.pool.release         19 active=0/10 idle=2/10 con=-843401329
Telerik.OpenAccess Information: 388 : sm.begin                    19 optimistic

Data Access Log with a connection state check (Leaks?) (note that there are 0 release calls)
Telerik.OpenAccess Information: 392 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 393 : sm.begin                    20 optimistic
Telerik.OpenAccess Information: 394 : driver.pool.alloc           20 active=1/10 idle=1/10 con=-843401329
Telerik.OpenAccess Information: 395 : driver.con.begin            9 ReadCommitted
Telerik.OpenAccess Information: 396 : sm.store                    20 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 397 : driver.con.createStat       9
Telerik.OpenAccess Information: 398 : driver.con.prepareCall      9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 399 : driver.stat.execQuery       9 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:33:09.9508"]
Telerik.OpenAccess Information: 400 : driver.rs.close
Telerik.OpenAccess Information: 401 : driver.con.commit           9
Telerik.OpenAccess Information: 402 : sm.begin                    20 optimistic
Telerik.OpenAccess Information: 403 : driver.con.begin            9 ReadCommitted
Telerik.OpenAccess Information: 404 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 405 : sm.begin                    21 optimistic
Telerik.OpenAccess Information: 406 : driver.pool.alloc           21 active=1/10 idle=0/10 con=-842352745
Telerik.OpenAccess Information: 407 : driver.con.begin            8 ReadCommitted
Telerik.OpenAccess Information: 408 : sm.store                    21 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 409 : driver.con.createStat       8
Telerik.OpenAccess Information: 410 : driver.con.prepareCall      8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 411 : driver.stat.execQuery       8 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:33:09.9616"]
Telerik.OpenAccess Information: 412 : driver.rs.close
Telerik.OpenAccess Information: 413 : driver.con.commit           8
Telerik.OpenAccess Information: 414 : sm.begin                    21 optimistic
Telerik.OpenAccess Information: 415 : driver.con.begin            8 ReadCommitted
Telerik.OpenAccess Information: 416 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 417 : sm.begin                    22 optimistic
Telerik.OpenAccess Information: 418 : driver.con.connect          10 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=MOERKFITZ-ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 419 : driver.con.createStat       10
Telerik.OpenAccess Information: 420 : driver.stat.executeNonQuery 10 SET LOCK_TIMEOUT 5000 []
Telerik.OpenAccess Information: 421 : driver.pool.alloc           22 active=1/10 idle=0/10 con=-843466865
Telerik.OpenAccess Information: 422 : driver.con.begin            10 ReadCommitted
Telerik.OpenAccess Information: 423 : sm.store                    22 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 424 : driver.con.createStat       10
Telerik.OpenAccess Information: 425 : driver.con.prepareCall      10 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 426 : driver.stat.execQuery       10 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:33:09.9756"]
Telerik.OpenAccess Information: 427 : driver.rs.close
Telerik.OpenAccess Information: 428 : driver.con.commit           10
Telerik.OpenAccess Information: 429 : sm.begin                    22 optimistic
Telerik.OpenAccess Information: 430 : driver.con.begin            10 ReadCommitted
Telerik.OpenAccess Information: 431 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 432 : sm.begin                    23 optimistic
Telerik.OpenAccess Information: 433 : driver.con.connect          11 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=MOERKFITZ-ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 434 : driver.con.createStat       11
Telerik.OpenAccess Information: 435 : driver.stat.executeNonQuery 11 SET LOCK_TIMEOUT 5000 []
Telerik.OpenAccess Information: 436 : driver.pool.alloc           23 active=1/10 idle=0/10 con=-843532401
Telerik.OpenAccess Information: 437 : driver.con.begin            11 ReadCommitted
Telerik.OpenAccess Information: 438 : sm.store                    23 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 439 : driver.con.createStat       11
Telerik.OpenAccess Information: 440 : driver.con.prepareCall      11 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 441 : driver.stat.execQuery       11 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:33:09.9896"]
Telerik.OpenAccess Information: 442 : driver.rs.close
Telerik.OpenAccess Information: 443 : driver.con.commit           11
Telerik.OpenAccess Information: 444 : sm.begin                    23 optimistic
Telerik.OpenAccess Information: 445 : driver.con.begin            11 ReadCommitted
Telerik.OpenAccess Information: 446 : pm.alloc                    idle 0/8
Telerik.OpenAccess Information: 447 : sm.begin                    24 optimistic
Telerik.OpenAccess Information: 448 : driver.con.connect          12 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=MOERKFITZ-ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 449 : driver.con.createStat       12
Telerik.OpenAccess Information: 450 : driver.stat.executeNonQuery 12 SET LOCK_TIMEOUT 5000 []
Telerik.OpenAccess Information: 451 : driver.pool.alloc           24 active=1/10 idle=0/10 con=-843597937
Telerik.OpenAccess Information: 452 : driver.con.begin            12 ReadCommitted
Telerik.OpenAccess Information: 453 : sm.store                    24 1 OID(s) delete 0 OID(s) 3 returnFieldsUpdatedBySM
Telerik.OpenAccess Information: 454 : driver.con.createStat       12
Telerik.OpenAccess Information: 455 : driver.con.prepareCall      12 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0
Telerik.OpenAccess Information: 456 : driver.stat.execQuery       12 declare @generated_ids table([LogId] int)
insert [MyEventLog] ([msg])
output inserted.[LogId] into @generated_ids
 VALUES (@p0)
select t.[LogId]
from @generated_ids as g join [MyEventLog] as t on g.[LogId] = t.[LogId]
where @@ROWCOUNT > 0 [@p0="12/13/2014 22:33:10.0057"]
Telerik.OpenAccess Information: 457 : driver.rs.close
Telerik.OpenAccess Information: 458 : driver.con.commit           12
Telerik.OpenAccess Information: 459 : sm.begin                    24 optimistic
Telerik.OpenAccess Information: 460 : driver.con.begin            12 ReadCommitted
Telerik.OpenAccess Information: 461 : driver.con.connect          13 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=MOERKFITZ-ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 462 : driver.con.createStat       13
Telerik.OpenAccess Information: 463 : driver.stat.executeNonQuery 13 SET LOCK_TIMEOUT 5000 []
Telerik.OpenAccess Information: 464 : driver.con.connect          14 MultipleActiveResultSets=true;Pooling=false;Enlist=false;data source=MOERKFITZ-ES-3\sqlexpress;initial catalog=DataAccessTestDatabase;integrated security=True
Telerik.OpenAccess Information: 465 : driver.con.createStat       14
Telerik.OpenAccess Information: 466 : driver.stat.executeNonQuery 14 SET LOCK_TIMEOUT 5000 []



7 Answers, 1 is accepted

Sort by
0
MarkInTexas
Top achievements
Rank 1
answered on 17 Dec 2014, 03:25 AM
Telerik? Curious to know your response to this issue.
Thanks,
Mark.
0
Boyan
Telerik team
answered on 17 Dec 2014, 07:56 AM
Hi Mark,

Thank you for the detailed explanation.

I noticed that in your second sample method that causes leaks you are actually obtaining a connection from Telerik Data Access. Please note that if you do that, Telerik Data Access will exclude this connection from its connection pool and will stop managing it. Thus when the context is actually disposed, the connection is not disposed which in turn is the most probable reason for the issue you are observing.
Managing connections in such way is more common when using the Telerik Data Access ADO API​. In those cases it is responsibility of the user to dispose the connection once it is no longer needed.

In the first example, the same behavior is not observed as the connection being managed by Telerik Data Access is disposed along with the context.

With that in mind, could I ask you to elaborate a bit more on the scenario that requires checking the connection state so we could advice you better in that situation?

I am looking forward to hearing from you.

Regards,
Boyan
Telerik
 
OpenAccess ORM is now Telerik Data Access. For more information on the new names, please, check out the Telerik Product Map.
 
0
MarkInTexas
Top achievements
Rank 1
answered on 17 Dec 2014, 03:53 PM
Boyan,
Thanks for the response.

I am now using the first version and handle a closed connection via an exception if thrown from the context and that approach definitely solves the issue.

Back to the second example (and more curious than anything)...

So the line of code below from example 2 actually creates a new connection ? (was thinking it checked the state of the "Current" connection). I am guessing that the Data Context at this point does not have a connection open but when this line of code is executed - a new connection is created and is in fact "open" but no longer part of the connection pool?
And now I have an orphaned connection object, that is, no longer managed by Context connection pool? Does that sound correct?
I did try obtaining that connection object and manually disposing of it - got exceptions doing that as well.

if (context.Connection.State == System.Data.ConnectionState.Open)

Thanks again,
Mark.

0
Boyan
Telerik team
answered on 19 Dec 2014, 12:03 PM
Hi Mark,

Yes, you are right. When you execute this code: context.Connection, you will in fact take a connection from Telerik Data Access connection pool. If this connection if not available in the connection pool it will created and served to you. Depending on its setting Telerik Data Access will create another connection in time to replace that one taken from its connection pool when it is needed again. Once such connection is served to you, it is indeed your responsibility to manage it. This connection will continue to take resources until it is disposed.

Could you please let us know what is the exception you got when disposing it and could you provide a code sample that demonstrates it? Modifying a bit your sample code (as show below), I was not able to reproduce such behavior.

for (int i = 0; i < num; i++)
{
    using (EntitiesModel context = new EntitiesModel())
    {
        var daCon = context.Connection;
        using (daCon)
        {
            if (context.Connection.State == System.Data.ConnectionState.Open)
            {
                context.Add(new Author
                {
                    Name = DateTime.Now.ToString("MM/dd/yyyy HH:mm:ss.fff")
                });
                context.SaveChanges();
            }
        }
         
    }
}


I hope this is helpful. Do let us know if you have more questions.

Regards,
Boyan
Telerik
 
OpenAccess ORM is now Telerik Data Access. For more information on the new names, please, check out the Telerik Product Map.
 
0
MarkInTexas
Top achievements
Rank 1
answered on 21 Dec 2014, 04:18 PM
Boyan,

That example does not throw exceptions. However, when the save occurs the context creates another connection (from the connection pool) to access (add and save the object) to the database, correct? During this there are 2 connections open - one created by the context and one opened manually. Did that manually opened connection come from the connection pool ?

Anway...

After looking at the example, it took me awhile to figure out how I got an exception. I believe it was something similar to below. In my haste in trying different approaches I had grabbed a connection and tried to call close on it - which does throw an exception.

And just to be clear : There is no way to check  the"Active" connection state in the context since it will open and close that connection when needed and instead rely on the fact an exception will be thrown via the context if there was a connection issue and I can handle that exception.

Thanks for your help, I believe we can mark this issue resolved.

  using (EntitiesModel context = new EntitiesModel())
  {
    OAConnection connection = context.Connection;
    if (connection.State == System.Data.ConnectionState.Open)
    {
      context.Add(new MyEventLog
      {
        Message = DateTime.Now.ToString("MM/dd/yyyy HH:mm:ss.fff")
      });
      context.SaveChanges();
    }
    connection.Close();
  }
}


0
Accepted
Boyan
Telerik team
answered on 22 Dec 2014, 05:04 PM
Hi Mark,

Yes, the this connection would come from the connection pool. Telerik Data Access will create and store a replacement connection eventually when such connection is needed.

Generally speaking, Telerik Data Access will check and make sure that connections it uses are healthy. If not, it will thrown an exception. If you would like to perform any additional checks, this could indeed be performed by obtaining a connection from Telerik Data Access as long as it is eventually disposed.

Further more, you could take advantage of the Connection Pool settings (described in more detail in this documentation article) - more precisely the Connection Health section. Those would allow you to control the way that Telerik Data Access performs its connection checks and apply any modification (if needed) so those settings are optimal for your current implementation scenario.

Also, referring to the code snippet you provided, you are right. A connection belonging to a context should not be closed in such way and it would indeed result in an exception.

Should you have any more question do let us know.

Regards,
Boyan
Telerik
 
OpenAccess ORM is now Telerik Data Access. For more information on the new names, please, check out the Telerik Product Map.
 
0
MarkInTexas
Top achievements
Rank 1
answered on 22 Dec 2014, 06:48 PM
Thanks for the great information.

Mark.
Tags
Development (API, general questions)
Asked by
MarkInTexas
Top achievements
Rank 1
Answers by
MarkInTexas
Top achievements
Rank 1
Boyan
Telerik team
Share this question
or