Hello,
I have written a little application (
compute surface and store in database) to test jonas performance. a session
statefull bean handles two others beans:
[Regis Le Brettevillois] Which JOnAS release do you use?
[Mohamed Boutkhil]I'm using both 2.2.2 and 2.2.7 version with rmi. I
observe the same behavior.
- a stateless which computes
surface
- and entitybean which saves
the result.
the client call statefull bean method in
loop. it works fine. but when two clients carry out it in the same time, the
server stops to execute client request from the second or third loop.
[Regis Le Brettevillois] Could you give more
information about the behavior? Are the both clients working on
the same entity bean? Do you have traces where the server is
stopped?
[Mohamed Boutkhil] Here are more explaination about
the behavior:
-each client calls the statefull
bean to have the circle (or sphere) surface with a variable
radius.
- then the statefull bean checks
in database (by using the findByPrimaryKeys() method of entityhome
object) if the surface was already computed.
- if yes, the result is return
to the client.
- if no, the statefull bean
computes the surface with the stateless bean help, stores the result in
database (by using the create method of entityhome object) and gives the
result to the client.
Note: I have tried to modify the
transaction attribute without changing.
Here is two different
logs.
Server crashing with
inconsistency error:
************************************************************************************************************************
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:421 ConnectionImpl.prepareStatement(String select reference from
SurfaceTable where reference=?)
RMI TCP Connection(9)-192.168.1.7
select reference from SurfaceTable where reference = sphere21
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 Object not found in database (findByPrimaryKey)
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 ConnectionImpl.close()
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 XAConnectionImpl.notifyClose()
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 ConnectionManager.connectionClosed(ConnectionEvent)
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 Pool: keep the Connection for this tx
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 ------------ ConnectionPool -----------------
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 Nb of item not associated with tx = 0
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 +++ Hashtable XAC -> item:
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 1 open=0 tx=1003863896488044
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:423 0 open=0 tx=1003863896421481
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:465 +++ FreeList:
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:465 +++ Associated with tx:
RMI TCP Connection(9)-192.168.1.7 :
0:30:36:465 0 open=0 tx=1003863896421481
RMI TCP
Connection(9)-192.168.1.7 : 0:30:36:465 Pool: Inconsistency detected
!
*******************************************************************************************************************************
Server stops:
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:347 EntityContextImpl.storeIfModified
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:347 JOnASStoreSurfaceContBean.ejbStore()
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 JOnASStoreSurfaceContBean.isModifiedData() -->
true
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 StoreSurfaceBean ejbStore
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 EntityContextImpl.getEJBObject
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 JEntityHome.getDataSource
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 ConnectionManager.getConnection()
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 ConnectionManager.getConnection()
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 Tx = 1003863964798987
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 Pool: reuse a Connection for Tx = 1003863964798987
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 ------------ ConnectionPool -----------------
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 Nb of item not associated with tx = 0
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 +++ Hashtable XAC -> item:
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 1 open=1 tx=1003863965005837
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 0 open=1 tx=1003863964798987
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 +++ FreeList:
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 +++ Associated with tx:
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 1 open=1 tx=1003863965005837
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 0 open=1 tx=1003863964798987
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:348 ---------------------------------------------
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 ConnectionManager: Reuse an XAConnection from the
pool
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 XAConnectionImpl.getConnection()
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 ConnectionImpl.setAutoCommit(boolean false)
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 XAConnectionImpl.getXAResource()
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 TransactionImpl.enlistResource(XAResource xares)
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 SubCoordinator.addResource(XAResource xares)
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 XAResource ---> isSameRM = true
org.objectweb.jonas.jdbc_xa.XAResourceImpl@bf072
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 XAResource ---> START
org.objectweb.jonas.jdbc_xa.XAResourceImpl@bf072
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 Xid = 1003863964798987
RMI TCP Connection(6)-192.168.1.7 :
0:31:43:349 ConnectionImpl.prepareStatement(String update SurfaceTable set
rayon=?, digits=?, surface=? where reference=?)
RMI TCP Connection(6)-192.168.1.7 update
SurfaceTable set rayon = 2 , digits = 10 , surface = 50.2654824576 where
reference = sphere2
PoolKeeper : 0:32:27:91 ------------
ConnectionPool -----------------
PoolKeeper : 0:32:27:91 Nb of item not
associated with tx = 0
PoolKeeper : 0:32:27:91 +++ Hashtable XAC
-> item:
PoolKeeper : 0:32:27:91 1 open=1
tx=1003863965005837
PoolKeeper : 0:32:27:91 0 open=1
tx=1003863964798987
PoolKeeper : 0:32:27:91 +++
FreeList:
PoolKeeper : 0:32:27:92 +++ Associated with
tx:
PoolKeeper : 0:32:27:92 1 open=1
tx=1003863965005837
PoolKeeper : 0:32:27:92 0 open=1
tx=1003863964798987
PoolKeeper : 0:32:27:92
---------------------------------------------
******************************************************End of
log**************************************************************
-
When the server stops, I have
tried to launch another client and the server stops again. And the
poolkeeper contain another tx open. I have set transaction timeout to 1
second (in jonas.properties) but nothing change. It seems the stop occurs
after the ejbStore method call
I hope there is not too much
log.
Mohamed.Boutkhil
If I remove entity bean from the
execution process, it works fine. therefore my entity bean management seems
not correct. the session bean which uses entity bean never removes it. it
just call findByPrimaryKeys() and create() methods. I don't know how handle
that.
[Regis Le Brettevillois] You don't need to manage the
entity bean life cycle, it's the container's
responsability.
Thanks for help..
Mohamed
Boukthil
