[jboss-user] [JCA/JBoss] - Re: Validation of destroyed connections

Thu Mar 1 12:52:57 EST 2007

I'm maintaining a pool with 5 connections and validations every minute. On creation, I assign them an id for monitoring. Here you can see a test case I've already done:

On startup jboss creates 5 connections (ids 1 to 5), and validates them every minute. In a given moment, the EIS closes all connections, so the validation of all of them fails and jboss destroys them:

18:24:25,314 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:25,314 INFO  [TAManagedConnection:249] Validating connection 2
18:24:25,314 INFO  [TAManagedConnection:290] I/O ERROR
18:24:25,314 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:24:25,314 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:25,314 WARN  [JBossManagedConnectionPool:657] 
18:24:25,314 INFO  [TAManagedConnection:150] Destroying connection...
18:24:25,314 INFO  [TAManagedConnection:160] Connection destroyed
18:24:25,314 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:25,314 INFO  [TAManagedConnection:249] Validating connection 3
18:24:25,314 INFO  [TAManagedConnection:290] I/O ERROR
18:24:25,314 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:24:25,314 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:25,314 WARN  [JBossManagedConnectionPool:657] 
18:24:25,314 INFO  [TAManagedConnection:150] Destroying connection...
18:24:25,314 INFO  [TAManagedConnection:160] Connection destroyed
18:24:25,314 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:25,314 INFO  [TAManagedConnection:249] Validating connection 4
18:24:25,314 INFO  [TAManagedConnection:290] I/O ERROR
18:24:25,314 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:24:25,314 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:25,314 WARN  [JBossManagedConnectionPool:657] 
18:24:25,314 INFO  [TAManagedConnection:150] Destroying connection...
18:24:25,314 INFO  [TAManagedConnection:160] Connection destroyed
18:24:25,314 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:25,314 INFO  [TAManagedConnection:249] Validating connection 5
18:24:25,314 INFO  [TAManagedConnection:290] I/O ERROR
18:24:25,314 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:24:25,314 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:25,314 WARN  [JBossManagedConnectionPool:657] 
18:24:25,314 INFO  [TAManagedConnection:150] Destroying connection...
18:24:25,314 INFO  [TAManagedConnection:160] Connection destroyed
18:24:25,314 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:25,314 INFO  [TAManagedConnection:249] Validating connection 1
18:24:25,329 INFO  [TAManagedConnection:290] I/O ERROR
18:24:25,329 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:24:25,329 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:25,329 WARN  [JBossManagedConnectionPool:657] 
18:24:25,329 INFO  [TAManagedConnection:150] Destroying connection...
18:24:25,329 INFO  [TAManagedConnection:160] Connection destroyed

Inmediately jboss creates 5 new connections (ids 6 to 10) in order to fill the pool:

18:24:25,329 INFO  [TAManagedConnection:111] Initiating connection...
18:24:25,329 INFO  [TAManagedConnection:118] Connection established
18:24:25,329 INFO  [TAManagedConnectionFactory:111] Connection created - number 6
18:24:25,329 INFO  [TAManagedConnection:111] Initiating connection...
18:24:25,329 INFO  [TAManagedConnection:118] Connection established
18:24:25,329 INFO  [TAManagedConnectionFactory:111] Connection created - number 7
18:24:25,329 INFO  [TAManagedConnection:111] Initiating connection...
18:24:25,329 INFO  [TAManagedConnection:118] Connection established
18:24:25,329 INFO  [TAManagedConnectionFactory:111] Connection created - number 8
18:24:25,329 INFO  [TAManagedConnection:111] Initiating connection...
18:24:25,329 INFO  [TAManagedConnection:118] Connection established
18:24:25,329 INFO  [TAManagedConnectionFactory:111] Connection created - number 9
18:24:25,329 INFO  [TAManagedConnection:111] Initiating connection...
18:24:25,329 INFO  [TAManagedConnection:118] Connection established
18:24:25,329 INFO  [TAManagedConnectionFactory:111] Connection created - number 10

30 seconds before, jboss validates the recently created connections:

18:24:55,330 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:55,330 INFO  [TAManagedConnection:249] Validating connection 6
18:24:55,345 INFO  [TAManagedConnection:275] Connection is valid
18:24:55,345 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:55,345 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:55,345 INFO  [TAManagedConnection:249] Validating connection 7
18:24:55,345 INFO  [TAManagedConnection:275] Connection is valid
18:24:55,345 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:55,345 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:55,345 INFO  [TAManagedConnection:249] Validating connection 8
18:24:55,345 INFO  [TAManagedConnection:275] Connection is valid
18:24:55,345 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:55,361 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:55,361 INFO  [TAManagedConnection:249] Validating connection 9
18:24:55,361 INFO  [TAManagedConnection:275] Connection is valid
18:24:55,361 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:24:55,361 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:24:55,361 INFO  [TAManagedConnection:249] Validating connection 10
18:24:55,377 INFO  [TAManagedConnection:275] Connection is valid
18:24:55,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED

but 30 seconds before this (and exactly 1 minute before the last unsuccessful validation) jboss validates the destroyed connections (ids 1 to 5):

18:25:25,377 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:25:25,377 INFO  [TAManagedConnection:249] Validating connection 2
18:25:25,377 INFO  [TAManagedConnection:299] Channel is not connected
18:25:25,377 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:25:25,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:25:25,377 WARN  [JBossManagedConnectionPool:657] 
18:25:25,377 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:25:25,377 INFO  [TAManagedConnection:249] Validating connection 3
18:25:25,377 INFO  [TAManagedConnection:299] Channel is not connected
18:25:25,377 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:25:25,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:25:25,377 WARN  [JBossManagedConnectionPool:657] 
18:25:25,377 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:25:25,377 INFO  [TAManagedConnection:249] Validating connection 4
18:25:25,377 INFO  [TAManagedConnection:299] Channel is not connected
18:25:25,377 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:25:25,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:25:25,377 WARN  [JBossManagedConnectionPool:657] 
18:25:25,377 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:25:25,377 INFO  [TAManagedConnection:249] Validating connection 5
18:25:25,377 INFO  [TAManagedConnection:299] Channel is not connected
18:25:25,377 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:25:25,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:25:25,377 WARN  [JBossManagedConnectionPool:657] 
18:25:25,377 INFO  [TAManagedConnectionFactory:142] VALIDATING CONNECTIONS....
18:25:25,377 INFO  [TAManagedConnection:249] Validating connection 1
18:25:25,377 INFO  [TAManagedConnection:299] Channel is not connected
18:25:25,377 INFO  [TAManagedConnectionFactory:150] Invalid connection found
18:25:25,377 INFO  [TAManagedConnectionFactory:154] VALIDATION FINISHED
18:25:25,377 WARN  [JBossManagedConnectionPool:657] 

As you can see, jboss is constantly validating destroyed connections, the more connections get invalid, the more the problem increases.

View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4024394#4024394

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4024394