-
1. Re: Validation of destroyed connections
weston.price Mar 1, 2007 11:38 AM (in response to bortx)The background validation feature only evaluates those connections that are currently in the pool. The destroy operation removes connections from the pool and as a result, they are not subject to validation. Can you give me more info as to why you think it is doing this?
May I notify ConnectionEventListener set on some event?
No, as this is unnecessary. The destroy occurs at the end of the ManagedConnection lifecycle and as such, there is no reason to notify the listener. -
2. Re: Validation of destroyed connections
bortx Mar 1, 2007 12:52 PM (in response to bortx)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. -
3. Re: Validation of destroyed connections
weston.price Mar 1, 2007 1:09 PM (in response to bortx)Yep. Looks like a bug. I will take care of it.
-
4. Re: Validation of destroyed connections
weston.price Mar 3, 2007 11:01 PM (in response to bortx)http://jira.jboss.org/jira/browse/JBAS-4170
This has been resolved in 4.2 and 5.0. -
5. Re: Validation of destroyed connections
bortx Mar 5, 2007 5:16 AM (in response to bortx)Thank you very much!
I've fixed it in my jboss-jca.jar and works perfectly.
Thanks! you rule!