I am having a very strange error. Perhaps, I have no clue of what I am
doing but here it goes.

Server: 3.0alpha

I have a SFSB (appManager) that talks to SLSB (Registry) that updates CMP
Entity (DescriptorCMP). 

in ejbRemove() of SFSB I call SLSB to update a particular Entity before
this appManager is gone. The call to SLSB succeeds, entity is updated but
on the return path of the invocation, the SLSB proxy throws a NPE. Here is
the trace:

18:15:09,426 DEBUG [org.jboss.tm.TxCapsule] Committed OK, tx=XidImpl
[FormatId=257, GlobalId=lib03//8, BranchQual=]
18:15:09,436 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//8, BranchQual=]
18:15:09,436 DEBUG [org.jboss.tm.TxCapsule] Reused instance for tx=XidImpl
[FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,436 DEBUG [org.jboss.tm.TxManager] began
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,436 DEBUG [org.jboss.tm.TxCapsule]
registerSynchronization(): Entered, tx=XidImpl [FormatId=257,
GlobalId=lib03//9, BranchQual=] status=STATUS_ACTIVE
18:15:09,436 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,436 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxCapsule]
registerSynchronization(): Entered, tx=XidImpl [FormatId=257,
GlobalId=lib03//9, BranchQual=] status=STATUS_ACTIVE
18:15:09,586 DEBUG [org.jboss.tm.TxCapsule]
registerSynchronization(): Entered, tx=XidImpl [FormatId=257,
GlobalId=lib03//9, BranchQual=] status=STATUS_ACTIVE
18:15:09,586 DEBUG [org.jboss.tm.TxCapsule]
registerSynchronization(): Entered, tx=XidImpl [FormatId=257,
GlobalId=lib03//9, BranchQual=] status=STATUS_ACTIVE
18:15:09,586 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,586 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 INFO  [Default] Released lock for petstore, 
18:15:09,596 INFO  [Default] Released lock for application: petstore
18:15:09,596 DEBUG [org.jboss.tm.TxManager] suspended
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 DEBUG [org.jboss.tm.TxManager] resumed
tx: TransactionImpl:XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=]
18:15:09,596 ERROR [Default] java.lang.NullPointerException
18:15:09,596 ERROR [Default]    at $Proxy8.releaseAppLock(Unknown Source)
18:15:09,596 ERROR [Default]    at
test.deployment.config.ejb.AppManagerBean.ejbRemove(Unknown Source)
18:15:09,596 ERROR [Default]    at java.lang.reflect.Method.invoke(Native
Method)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.StatefulSessionFilePersistenceManager.removeSession(StatefulSessionFilePersistenceManager.java:309)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.StatefulSessionContainer.remove(StatefulSessionContainer.java:359)
18:15:09,596 ERROR [Default]    at java.lang.reflect.Method.invoke(Native
Method)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.StatefulSessionContainer$ContainerInterceptor.invoke(StatefulSessionContainer.java:677)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.SecurityInterceptor.invoke(SecurityInterceptor.java:127)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.StatefulSessionInstanceInterceptor.invoke(StatefulSessionInstanceInterceptor.java:241)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.AbstractTxInterceptor.invokeNext(AbstractTxInterceptor.java:96)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.TxInterceptorCMT.runWithTransactions(TxInterceptorCMT.java:155)
18:15:09,596 ERROR [Default]    at
org.jboss.ejb.plugins.TxInterceptorCMT.invoke(TxInterceptorCMT.java:64)
18:15:09,606 ERROR [Default]    at
org.jboss.ejb.plugins.LogInterceptor.invoke(LogInterceptor.java:166)
18:15:09,606 ERROR [Default]    at
org.jboss.ejb.StatefulSessionContainer.invoke(StatefulSessionContainer.java:346)
18:15:09,606 ERROR [Default]    at
org.jboss.ejb.Container.invoke(Container.java:533)
18:15:09,606 ERROR [Default]    at
com.sun.management.jmx.MBeanServerImpl.invoke(MBeanServerImpl.java:1555)
18:15:09,606 ERROR [Default]    at
com.sun.management.jmx.MBeanServerImpl.invoke(MBeanServerImpl.java:1523)
18:15:09,606 ERROR [Default]    at
org.jboss.ejb.plugins.jrmp.server.JRMPContainerInvoker.invoke(JRMPContainerInvoker.java:411)
18:15:09,606 ERROR [Default]    at java.lang.reflect.Method.invoke(Native
Method)
18:15:09,606 ERROR [Default]    at
sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:241)
18:15:09,606 ERROR [Default]    at
sun.rmi.transport.Transport$1.run(Transport.java:142)
18:15:09,606 ERROR [Default]    at
java.security.AccessController.doPrivileged(Native Method)
18:15:09,606 ERROR [Default]    at
sun.rmi.transport.Transport.serviceCall(Transport.java:139)
18:15:09,606 ERROR [Default]    at
sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:443)
18:15:09,606 ERROR [Default]    at
sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:643)
18:15:09,606 ERROR [Default]    at java.lang.Thread.run(Thread.java:484)
18:15:09,606 DEBUG [org.jboss.tm.TxCapsule] Committing, tx=XidImpl
[FormatId=257, GlobalId=lib03//9, BranchQual=], status=STATUS_ACTIVE
18:15:09,616 DEBUG [org.jboss.pool.ObjectPool.J2EESplitDB] Pool
J2EESplitDB [1/1/10] gave out pooled
object: org.jboss.resource.adapter.jdbc.local.JDBCManagedConnection@474e45
18:15:09,616 DEBUG [org.jboss.tm.TxCapsule]
registerSynchronization(): Entered, tx=XidImpl [FormatId=257,
GlobalId=lib03//9, BranchQual=] status=STATUS_ACTIVE
18:15:09,616 DEBUG
[org.jboss.resource.JBossConnectionListenerImpl.J2EESplitDB] Connection
handle 'org.jboss.resource.adapter.jdbc.local.ConnectionInPool@2631cc'
issued by connection manager
'org.jboss.resource.connectionmanager.jboss.MinervaSharedLocalCM@4e1bea'
from mcf
'org.jboss.resource.adapter.jdbc.local.JDBCManagedConnectionFactory@3c9c31'
18:15:09,616 DEBUG [org.jboss.ejb.plugins.jaws.jdbc.JDBCCommand] Store
command executing: UPDATE DESCRIPTOR SET LOCKKEY=? WHERE OBJNAME=? AND
DESCTYPE=?
18:15:09,616 DEBUG [org.jboss.ejb.plugins.jaws.jdbc.JDBCCommand] Set
parameter: idx=1, jdbcType=INTEGER, value=-1
18:15:09,616 DEBUG [org.jboss.ejb.plugins.jaws.jdbc.JDBCCommand] Set
parameter: idx=2, jdbcType=VARCHAR, value=petstore
18:15:09,616 DEBUG [org.jboss.ejb.plugins.jaws.jdbc.JDBCCommand] Set
parameter: idx=3, jdbcType=VARCHAR, value=
18:15:09,616 DEBUG [org.jboss.ejb.plugins.jaws.jdbc.JDBCUpdateCommand]
Rows affected = 1
18:15:09,616 DEBUG
[org.jboss.resource.JBossConnectionListenerImpl.J2EESplitDB] Connection
handle 'org.jboss.resource.adapter.jdbc.local.ConnectionInPool@2631cc'
closed from connection manager
'org.jboss.resource.connectionmanager.jboss.MinervaSharedLocalCM@4e1bea'
from mcf
'org.jboss.resource.adapter.jdbc.local.JDBCManagedConnectionFactory@3c9c31'
18:15:09,616 DEBUG [org.jboss.tm.TxCapsule] Before completion done,
tx=XidImpl [FormatId=257, GlobalId=lib03//9, BranchQual=],
status=STATUS_ACTIVE
18:15:09,616 DEBUG [org.jboss.tm.TxCapsule] Zero phase commit: No
resources.
18:15:09,616 DEBUG [org.jboss.pool.ObjectPool.J2EESplitDB] Pool
J2EESplitDB [0/1/10] returned object
org.jboss.resource.adapter.jdbc.local.JDBCManagedConnection@474e45 to the
pool.
18:15:09,616 DEBUG [org.jboss.tm.TxCapsule] Committed OK, tx=XidImpl
[FormatId=257, GlobalId=lib03//9, BranchQual=]


the two messages from INFO [Default] about locks being released, are from
the SLSB and DescriptorCMP saying that the entity has been updated.

What is happening?

-------------------------------------------------------------------------
Anatoly Akkerman
Computer Science Dept.
Courant Institute of Mathematical Sciences, NYU
715 Broadway, #719                      Tel: 212 998-3493
New York, NY 10003                      Fax: 212 995-4123
-------------------------------------------------------------------------


_______________________________________________
Jboss-development mailing list
[EMAIL PROTECTED]
https://lists.sourceforge.net/lists/listinfo/jboss-development

Reply via email to