[JBoss Cache: Core Edition] - Re: Far cache problem (3.0.2) tx problems
by rs1050
I tried using jdbc cacheloader with this config:
cache.jdbc.table.name=jbosscache
cache.jdbc.table.create=true
cache.jdbc.table.drop=true
cache.jdbc.table.primarykey=jbosscache_pk
cache.jdbc.fqn.column=fqn
cache.jdbc.fqn.type=VARCHAR(255)
cache.jdbc.node.column=node
cache.jdbc.node.type=BLOB
cache.jdbc.parent.column=parent
cache.jdbc.driver=com.mysql.jdbc.Driver
cache.jdbc.url=jdbc:mysql://xxxx
cache.jdbc.user=xxxx
cache.jdbc.password=xxxxx
for some reason i got an exception which was absent in TcpDelegating cache loader...
20:56:59,384 [http--8080-0$11387974] INFO TestUser:getSomething:13 - yoyo:
20:56:59,386 [http--8080-0$11387974] TRACE ComponentRegistry:invocationsAllowed:877 - Testing if invocations are allowed.
20:56:59,389 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:141 - Invoked with command GetNodeCommand{fqn=/user/1:86} and InvocationContext [InvocationContext{transaction=null, globalTransaction=null, transactionContext=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false, dataVersion=null, suppressLocking=false, lockAcquisitionTimeout=-1, forceDataGravitation=false, skipDataGravitation=false, forceAsynchronous=false, forceSynchronous=false}, originLocal=true}]
20:57:00,311 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:00,312 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:72 - Setting tx as null and gtx as null
20:57:00,312 [http--8080-0$11387974] TRACE MVCCNodeHelper:wrapNodeForReading:136 - Node /user/1:86 is not in context, fetching from container.
20:57:00,313 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:mustLoad:417 - Node [/user/1:86] is null in memory. Must load? true
20:57:00,325 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:loadIfNeeded:293 - load element /user/1:86 mustLoad=true
20:57:00,326 [http--8080-0$11387974] TRACE MVCCLockManager:lockAndRecord:126 - Attempting to lock /user/1:86
20:57:00,365 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@c787d7
20:57:00,366 [http--8080-0$11387974] TRACE JDBCCacheLoader:prepareAndLogStatement:116 - Executing SQL statement [SELECT '1' FROM jbosscache WHERE fqn = ?] with params [/user/1:86]
20:57:00,369 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:close:176 - closed non tx connection: com.mysql.jdbc.JDBC4Connection@c787d7
20:57:00,370 [http--8080-0$11387974] TRACE MVCCNodeHelper:wrapNodeForReading:136 - Node /user/1:86 is not in context, fetching from container.
20:57:00,451 [http--8080-0$11387974] TRACE CallInterceptor:handleDefault:98 - Executing command: GetNodeCommand{fqn=/user/1:86}.
20:57:00,452 [http--8080-0$11387974] TRACE GetNodeCommand:perform:70 - Found node, returning null
20:57:00,453 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:cleanupLocks:374 - Releasing lock on [/user/1:86] for owner Thread[http--8080-0$11387974,5,main]
20:57:00,454 [http--8080-0$11387974] TRACE MVCCLockManager:unlock:140 - Attempting to unlock /user/1:86
20:57:00,456 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:208 - Resetting invocation-scope options
20:57:00,464 [http--8080-0$11387974] DEBUG NearCacheImpl:getValue:42 - for fqn: /user/1:86 got this value from cache: null
20:57:00,465 [http--8080-0$11387974] INFO UserManagerImpl:getUser:113 - user for id: 1:86 NOT found in cache, will get from DB
20:57:00,473 [http--8080-0$11387974] DEBUG JtaTransactionManager:lookupTransactionManager:587 - Retrieving JTA TransactionManager from JNDI location [java:/TransactionManager]
20:57:00,475 [http--8080-0$11387974] DEBUG JtaTransactionManager:findUserTransaction:676 - JTA UserTransaction found at default JNDI location [java:comp/UserTransaction]
20:57:00,480 [http--8080-0$11387974] INFO JtaTransactionManager:checkUserTransactionAndTransactionManager:488 - Using JTA UserTransaction: UserTransactionProxy[]
20:57:00,482 [http--8080-0$11387974] INFO JtaTransactionManager:checkUserTransactionAndTransactionManager:499 - Using JTA TransactionManager: TransactionManagerImpl[]
20:57:00,490 [http--8080-0$11387974] DEBUG JtaTransactionManager:findTransactionSynchronizationRegistry:777 - JTA 1.1 [javax.transaction.TransactionSynchronizationRegistry] not available
20:57:00,868 [http--8080-0$11387974] INFO TestUser:getSomething:19 - updating user with good value: TMUserImpl{_id=1:86, _username='asdf', _SLUsername='1233712620868', _password='sdf{romanSalt}', _email='asdf(a)xomc.om233', _userStateCd=A}
20:57:00,872 [http--8080-0$11387974] INFO UserManagerImpl:updateUser:54 - user TX before start of TX: UserTransactionProxy[], status: 6
20:57:00,873 [http--8080-0$11387974] INFO UserManagerImpl:updateUser:59 - started user TX: UserTransactionProxy[], status: 0
20:57:00,873 [http--8080-0$11387974] DEBUG NearCacheImpl:setValue:53 - setting value for object:/user/1:86 in cacheId:3
20:57:01,108 [http--8080-0$11387974] TRACE ComponentRegistry:invocationsAllowed:877 - Testing if invocations are allowed.
20:57:01,109 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:141 - Invoked with command GetNodeCommand{fqn=/user/1:86} and InvocationContext [InvocationContext{transaction=null, globalTransaction=null, transactionContext=null, optionOverrides=Option{failSilently=false, cacheModeLocal=false, dataVersion=null, suppressLocking=false, lockAcquisitionTimeout=-1, forceDataGravitation=false, skipDataGravitation=false, forceAsynchronous=false, forceSynchronous=false}, originLocal=true}]
20:57:01,121 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:01,121 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:72 - Setting tx as Transaction[01:bbb6a9a6] and gtx as null
20:57:01,122 [http--8080-0$11387974] TRACE TxInterceptor:attachGlobalTransaction:446 - local transaction exists - registering global tx if not present for Thread[http--8080-0$11387974,5,main]
20:57:01,123 [http--8080-0$11387974] TRACE TxInterceptor:attachGlobalTransaction:451 - Associated gtx in txTable is null
20:57:01,134 [http--8080-0$11387974] TRACE TransactionTable:getCurrentTransaction:450 - created new GTX: GlobalTransaction::1, local TX=Transaction[01:bbb6a9a6]
20:57:01,136 [http--8080-0$11387974] TRACE TxInterceptor:registerTransaction:719 - Registering sync handler for tx Transaction[01:bbb6a9a6], gtx GlobalTransaction::1
20:57:01,141 [http--8080-0$11387974] TRACE TxInterceptor:registerHandler:744 - registering for TX completion: SynchronizationHandler(TxInterceptor.LocalSynchronizationHandler(gtx=GlobalTransaction::1, tx=com.caucho.transaction.TransactionImpl@29842687))
20:57:01,153 [http--8080-0$11387974] INFO TMCacheListener:onNodeTransactionregistered:29 - : EventImpl{type=TRANSACTION_REGISTERED,pre=false, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=null, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,155 [http--8080-0$11387974] TRACE MVCCNodeHelper:wrapNodeForReading:136 - Node /user/1:86 is not in context, fetching from container.
20:57:01,162 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:mustLoad:417 - Node [/user/1:86] is null in memory. Must load? true
20:57:01,170 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:loadIfNeeded:293 - load element /user/1:86 mustLoad=true
20:57:01,171 [http--8080-0$11387974] TRACE MVCCLockManager:lockAndRecord:126 - Attempting to lock /user/1:86
20:57:01,197 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@26c7da
20:57:01,198 [http--8080-0$11387974] TRACE JDBCCacheLoader:prepareAndLogStatement:116 - Executing SQL statement [SELECT '1' FROM jbosscache WHERE fqn = ?] with params [/user/1:86]
20:57:01,200 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:close:176 - closed non tx connection: com.mysql.jdbc.JDBC4Connection@26c7da
20:57:01,201 [http--8080-0$11387974] TRACE MVCCNodeHelper:wrapNodeForReading:136 - Node /user/1:86 is not in context, fetching from container.
20:57:01,283 [http--8080-0$11387974] TRACE CallInterceptor:handleDefault:98 - Executing command: GetNodeCommand{fqn=/user/1:86}.
20:57:01,284 [http--8080-0$11387974] TRACE GetNodeCommand:perform:70 - Found node, returning null
20:57:01,285 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:doAfterCall:355 - Nothing to do since there is a transaction in scope.
20:57:01,286 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:208 - Resetting invocation-scope options
20:57:01,287 [http--8080-0$11387974] TRACE ComponentRegistry:invocationsAllowed:877 - Testing if invocations are allowed.
20:57:01,296 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:141 - Invoked with command PutKeyValueCommand{fqn=/user/1:86, dataVersion=null, globalTransaction=GlobalTransaction::1, key=d, value=TMUserImpl{_id=1:86, _username='asdf', _SLUsername='1233712620868', _password='sdf{romanSalt}', _email='asdf(a)xomc.om233', _userStateCd=A}} and InvocationContext [InvocationContext{transaction=Transaction[01:bbb6a9a6], globalTransaction=GlobalTransaction::1, transactionContext=TransactionEntry
modificationList: null, optionOverrides=Option{failSilently=false, cacheModeLocal=false, dataVersion=null, suppressLocking=false, lockAcquisitionTimeout=-1, forceDataGravitation=false, skipDataGravitation=false, forceAsynchronous=false, forceSynchronous=false}, originLocal=true}]
20:57:01,306 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:01,307 [http--8080-0$11387974] TRACE InvocationContextInterceptor:setTransactionalContext:72 - Setting tx as Transaction[01:bbb6a9a6] and gtx as GlobalTransaction::1
20:57:01,308 [http--8080-0$11387974] TRACE TxInterceptor:attachGlobalTransaction:446 - local transaction exists - registering global tx if not present for Thread[http--8080-0$11387974,5,main]
20:57:01,309 [http--8080-0$11387974] TRACE TxInterceptor:attachGlobalTransaction:451 - Associated gtx in txTable is GlobalTransaction::1
20:57:01,321 [http--8080-0$11387974] TRACE TxInterceptor:registerTransaction:732 - Transaction Transaction[01:bbb6a9a6] is already registered.
20:57:01,328 [http--8080-0$11387974] TRACE MVCCNodeHelper:wrapNodeForReading:136 - Node /user/1:86 is not in context, fetching from container.
20:57:01,328 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:mustLoad:417 - Node [/user/1:86] is null in memory. Must load? true
20:57:01,329 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:loadIfNeeded:293 - load element /user/1:86 mustLoad=true
20:57:01,339 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:loadData:494 - Attempting to load data for /user/1:86
20:57:01,368 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@13ccea
20:57:01,369 [http--8080-0$11387974] TRACE JDBCCacheLoader:prepareAndLogStatement:116 - Executing SQL statement [SELECT node FROM jbosscache WHERE fqn = ?] with params [/user/1:86]
20:57:01,371 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:close:176 - closed non tx connection: com.mysql.jdbc.JDBC4Connection@13ccea
20:57:01,373 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:loadData:497 - Node /user/1:86 exists? false
20:57:01,464 [http--8080-0$11387974] TRACE MVCCLockManager:lockAndRecord:126 - Attempting to lock /user
20:57:01,466 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_CREATED,pre=true, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=/user, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,470 [http--8080-0$11387974] TRACE MVCCNodeFactory:createChildNode:140 - Created new child with fqn [/user]
20:57:01,475 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_CREATED,pre=false, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=/user, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,477 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_CREATED,pre=true, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=/user/1:86, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,485 [http--8080-0$11387974] TRACE MVCCNodeFactory:createChildNode:140 - Created new child with fqn [/user/1:86]
20:57:01,486 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_CREATED,pre=false, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=/user/1:86, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,487 [http--8080-0$11387974] TRACE PutKeyValueCommand:perform:76 - Perform('GlobalTransaction::1', '/user/1:86', k='d', v='TMUserImpl{_id=1:86, _username='asdf', _SLUsername='1233712620868', _password='sdf{romanSalt}', _email='asdf(a)xomc.om233', _userStateCd=A}')
20:57:01,496 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_MODIFIED,pre=true, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=PUT_DATA, data={}, fqn=/user/1:86, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,497 [http--8080-0$11387974] TRACE PutKeyValueCommand:perform:88 - Old value is null, dataLoaded=false
20:57:01,499 [http--8080-0$11387974] INFO TMCacheListener:onNodeEvent:41 - : EventImpl{type=NODE_MODIFIED,pre=false, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=PUT_DATA, data={d=TMUserImpl{_id=1:86, _username='asdf', _SLUsername='1233712620868', _password='sdf{romanSalt}', _email='asdf(a)xomc.om233', _userStateCd=A}}, fqn=/user/1:86, transaction=Transaction[01:bbb6a9a6], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:01,511 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:doAfterCall:355 - Nothing to do since there is a transaction in scope.
20:57:02,116 [http--8080-0$11387974] TRACE InvocationContextInterceptor:handleAll:208 - Resetting invocation-scope options
20:57:02,131 [http--8080-0$11387974] INFO UserManagerImpl:updateUser:87 - committing tx
20:57:02,132 [http--8080-0$11387974] TRACE TxInterceptor:beforeCompletion:865 - Running beforeCompletion on gtx GlobalTransaction::1
20:57:02,133 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:02,134 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:72 - Setting tx as Transaction[01:bbb6a9a6] and gtx as GlobalTransaction::1
20:57:02,139 [http--8080-0$11387974] TRACE ComponentRegistry:invocationsAllowed:877 - Testing if invocations are allowed.
20:57:02,140 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:02,140 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:72 - Setting tx as Transaction[01:bbb6a9a6] and gtx as GlobalTransaction::1
20:57:02,142 [http--8080-0$11387974] TRACE CacheStoreInterceptor:handlePrepareCommand:195 - transactional so don't put stuff in the cloader yet.
20:57:02,148 [http--8080-0$11387974] TRACE CacheStoreInterceptor:prepareCacheLoader:331 - Cache loader modification list: [PutKeyValueCommand{fqn=/user/1:86, dataVersion=null, globalTransaction=GlobalTransaction::1, key=d, value=TMUserImpl{_id=1:86, _username='asdf', _SLUsername='1233712620868', _password='sdf{romanSalt}', _email='asdf(a)xomc.om233', _userStateCd=A}}]
20:57:02,155 [http--8080-0$11387974] TRACE CacheStoreInterceptor:prepareCacheLoader:339 - Converted method calls to cache loader modifications. List size: 1
20:57:02,192 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@12c9015
20:57:02,198 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:prepare:83 - opened tx connection: tx=GlobalTransaction::1, con=com.mysql.jdbc.JDBC4Connection@12c9015
20:57:02,200 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@12c9015
20:57:02,201 [http--8080-0$11387974] TRACE JDBCCacheLoader:prepareAndLogStatement:116 - Executing SQL statement [SELECT node FROM jbosscache WHERE fqn = ?] with params [/user/1:86]
20:57:02,284 [http--8080-0$11387974] TRACE NonManagedConnectionFactory:getConnection:107 - using connection: com.mysql.jdbc.JDBC4Connection@12c9015
20:57:02,285 [http--8080-0$11387974] TRACE JDBCCacheLoader:prepareAndLogStatement:116 - Executing SQL statement [INSERT INTO jbosscache (fqn, node, parent) SELECT ?, ?, ? FROM jbosscache_D WHERE NOT EXISTS (SELECT fqn FROM jbosscache WHERE fqn = ?)]
20:57:02,286 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:doAfterCall:355 - Nothing to do since there is a transaction in scope.
20:57:02,295 [http--8080-0$11387974] WARN TxInterceptor:beforeCompletion:1083 - Caught exception, will now set transaction to roll back
java.lang.NullPointerException
at org.jboss.cache.marshall.VersionAwareMarshaller.objectToObjectStream(VersionAwareMarshaller.java:334)
at org.jboss.cache.marshall.VersionAwareMarshaller.objectToBuffer(VersionAwareMarshaller.java:179)
at org.jboss.cache.loader.AdjListJDBCCacheLoader.marshall(AdjListJDBCCacheLoader.java:781)
at org.jboss.cache.loader.AdjListJDBCCacheLoader.populatePreparedStatementForInsert(AdjListJDBCCacheLoader.java:560)
at org.jboss.cache.loader.AdjListJDBCCacheLoader.insertNode(AdjListJDBCCacheLoader.java:525)
at org.jboss.cache.loader.JDBCCacheLoader.addNewSubtree(JDBCCacheLoader.java:348)
at org.jboss.cache.loader.JDBCCacheLoader.put(JDBCCacheLoader.java:108)
at org.jboss.cache.loader.AbstractCacheLoader.put(AbstractCacheLoader.java:303)
at org.jboss.cache.loader.AdjListJDBCCacheLoader.prepare(AdjListJDBCCacheLoader.java:251)
at org.jboss.cache.interceptors.CacheStoreInterceptor.prepareCacheLoader(CacheStoreInterceptor.java:343)
at org.jboss.cache.interceptors.CacheStoreInterceptor.handlePrepareCommand(CacheStoreInterceptor.java:196)
at org.jboss.cache.interceptors.base.SkipCheckChainedInterceptor.visitPrepareCommand(SkipCheckChainedInterceptor.java:310)
at org.jboss.cache.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:68)
at org.jboss.cache.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.jboss.cache.interceptors.MVCCLockingInterceptor.handlePrepareCommand(MVCCLockingInterceptor.java:328)
at org.jboss.cache.interceptors.base.PrePostProcessingCommandInterceptor.visitPrepareCommand(PrePostProcessingCommandInterceptor.java:383)
at org.jboss.cache.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:68)
at org.jboss.cache.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.jboss.cache.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:131)
at org.jboss.cache.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:140)
at org.jboss.cache.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:68)
at org.jboss.cache.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.jboss.cache.interceptors.NotificationInterceptor.visitPrepareCommand(NotificationInterceptor.java:50)
at org.jboss.cache.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:68)
at org.jboss.cache.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:116)
at org.jboss.cache.interceptors.TxInterceptor.runPreparePhase(TxInterceptor.java:648)
at org.jboss.cache.interceptors.TxInterceptor$LocalSynchronizationHandler.beforeCompletion(TxInterceptor.java:1065)
at org.jboss.cache.interceptors.OrderedSynchronizationHandler.beforeCompletion(OrderedSynchronizationHandler.java:81)
at com.caucho.transaction.TransactionImpl.callBeforeCompletion(TransactionImpl.java:843)
at com.caucho.transaction.TransactionImpl.commit(TransactionImpl.java:582)
at com.caucho.transaction.TransactionManagerImpl.commit(TransactionManagerImpl.java:271)
at com.caucho.jca.UserTransactionImpl.commit(UserTransactionImpl.java:436)
at com.caucho.jca.UserTransactionProxy.commit(UserTransactionProxy.java:167)
at com.threadmap.site.user.impl.UserManagerImpl.updateUser(UserManagerImpl.java:88)
at com.threadmap.tapestry.pages.user.TestUser.getSomething(TestUser.java:20)
at $PropertyConduit_11f3f0052bc.get($PropertyConduit_11f3f0052bc.java)
at org.apache.tapestry5.internal.bindings.PropBinding.get(PropBinding.java:53)
at org.apache.tapestry5.internal.structure.ExpansionPageElement.render(ExpansionPageElement.java:47)
at org.apache.tapestry5.internal.services.RenderQueueImpl.run(RenderQueueImpl.java:72)
at org.apache.tapestry5.internal.services.PageRenderQueueImpl.render(PageRenderQueueImpl.java:121)
at $PageRenderQueue_11f3f0052c3.render($PageRenderQueue_11f3f0052c3.java)
at $PageRenderQueue_11f3f0052c1.render($PageRenderQueue_11f3f0052c1.java)
at org.apache.tapestry5.services.TapestryModule$19.renderMarkup(TapestryModule.java:1200)
at org.apache.tapestry5.services.TapestryModule$29.renderMarkup(TapestryModule.java:1580)
at $MarkupRenderer_11f3f0052c7.renderMarkup($MarkupRenderer_11f3f0052c7.java)
at org.apache.tapestry5.services.TapestryModule$28.renderMarkup(TapestryModule.java:1561)
at $MarkupRenderer_11f3f0052c7.renderMarkup($MarkupRenderer_11f3f0052c7.java)
at org.apache.tapestry5.services.TapestryModule$27.renderMarkup(TapestryModule.java:1543)
at $MarkupRenderer_11f3f0052c7.renderMarkup($MarkupRenderer_11f3f0052c7.java)
at org.apache.tapestry5.services.TapestryModule$26.renderMarkup(TapestryModule.java:1525)
at $MarkupRenderer_11f3f0052c7.renderMarkup($MarkupRenderer_11f3f0052c7.java)
at org.apache.tapestry5.services.TapestryModule$25.renderMarkup(TapestryModule.java:1495)
at $MarkupRenderer_11f3f0052c7.renderMarkup($MarkupRenderer_11f3f0052c7.java)
at $MarkupRenderer_11f3f0052c0.renderMarkup($MarkupRenderer_11f3f0052c0.java)
at org.apache.tapestry5.internal.services.PageMarkupRendererImpl.renderPageMarkup(PageMarkupRendererImpl.java:64)
at $PageMarkupRenderer_11f3f0052be.renderPageMarkup($PageMarkupRenderer_11f3f0052be.java)
at org.apache.tapestry5.internal.services.PageResponseRendererImpl.renderPageResponse(PageResponseRendererImpl.java:57)
at $PageResponseRenderer_11f3f005293.renderPageResponse($PageResponseRenderer_11f3f005293.java)
at org.apache.tapestry5.internal.services.PageRenderRequestHandlerImpl.handle(PageRenderRequestHandlerImpl.java:59)
at org.apache.tapestry5.services.TapestryModule$35.handle(TapestryModule.java:1779)
at $PageRenderRequestHandler_11f3f005294.handle($PageRenderRequestHandler_11f3f005294.java)
at $PageRenderRequestHandler_11f3f005287.handle($PageRenderRequestHandler_11f3f005287.java)
at org.apache.tapestry5.internal.services.PageRenderDispatcher.process(PageRenderDispatcher.java:92)
at org.apache.tapestry5.internal.services.PageRenderDispatcher.dispatch(PageRenderDispatcher.java:71)
at $Dispatcher_11f3f00528d.dispatch($Dispatcher_11f3f00528d.java)
at $Dispatcher_11f3f005280.dispatch($Dispatcher_11f3f005280.java)
at org.apache.tapestry5.services.TapestryModule$17.service(TapestryModule.java:1029)
at com.threadmap.tapestry.services.AppModule$1.service(AppModule.java:105)
at $RequestFilter_11f3f00527f.service($RequestFilter_11f3f00527f.java)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.internal.services.LocalizationFilter.service(LocalizationFilter.java:42)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.internal.services.RequestErrorFilter.service(RequestErrorFilter.java:26)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.services.TapestryModule$3.service(TapestryModule.java:621)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.services.TapestryModule$2.service(TapestryModule.java:611)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.internal.services.StaticFilesFilter.service(StaticFilesFilter.java:85)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at org.apache.tapestry5.internal.services.CheckForUpdatesFilter$2.invoke(CheckForUpdatesFilter.java:93)
at org.apache.tapestry5.internal.services.CheckForUpdatesFilter$2.invoke(CheckForUpdatesFilter.java:84)
at org.apache.tapestry5.ioc.internal.util.ConcurrentBarrier.withRead(ConcurrentBarrier.java:83)
at org.apache.tapestry5.internal.services.CheckForUpdatesFilter.service(CheckForUpdatesFilter.java:106)
at $RequestHandler_11f3f005281.service($RequestHandler_11f3f005281.java)
at $RequestHandler_11f3f005277.service($RequestHandler_11f3f005277.java)
at org.apache.tapestry5.services.TapestryModule$16.service(TapestryModule.java:1007)
at org.apache.tapestry5.upload.internal.services.MultipartServletRequestFilter.service(MultipartServletRequestFilter.java:44)
at $HttpServletRequestHandler_11f3f005278.service($HttpServletRequestHandler_11f3f005278.java)
at org.apache.tapestry5.internal.services.IgnoredPathsFilter.service(IgnoredPathsFilter.java:62)
at $HttpServletRequestFilter_11f3f005276.service($HttpServletRequestFilter_11f3f005276.java)
at $HttpServletRequestHandler_11f3f005278.service($HttpServletRequestHandler_11f3f005278.java)
at $HttpServletRequestHandler_11f3f005274.service($HttpServletRequestHandler_11f3f005274.java)
at org.apache.tapestry5.TapestryFilter.doFilter(TapestryFilter.java:179)
at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:378)
at org.springframework.security.intercept.web.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:109)
at org.springframework.security.intercept.web.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:83)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.SessionFixationProtectionFilter.doFilterHttp(SessionFixationProtectionFilter.java:52)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.ExceptionTranslationFilter.doFilterHttp(ExceptionTranslationFilter.java:101)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.providers.anonymous.AnonymousProcessingFilter.doFilterHttp(AnonymousProcessingFilter.java:105)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.rememberme.RememberMeProcessingFilter.doFilterHttp(RememberMeProcessingFilter.java:109)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.wrapper.SecurityContextHolderAwareRequestFilter.doFilterHttp(SecurityContextHolderAwareRequestFilter.java:91)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.basicauth.BasicProcessingFilter.doFilterHttp(BasicProcessingFilter.java:174)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.AbstractProcessingFilter.doFilterHttp(AbstractProcessingFilter.java:277)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.ui.logout.LogoutFilter.doFilterHttp(LogoutFilter.java:89)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.context.HttpSessionContextIntegrationFilter.doFilterHttp(HttpSessionContextIntegrationFilter.java:235)
at org.springframework.security.ui.SpringSecurityFilter.doFilter(SpringSecurityFilter.java:53)
at org.springframework.security.util.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:390)
at org.springframework.security.util.FilterChainProxy.doFilter(FilterChainProxy.java:175)
at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:236)
at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:167)
at com.caucho.server.dispatch.FilterFilterChain.doFilter(FilterFilterChain.java:87)
at com.caucho.server.webapp.WebAppFilterChain.doFilter(WebAppFilterChain.java:187)
at com.caucho.server.dispatch.ServletInvocation.service(ServletInvocation.java:265)
at com.caucho.server.http.HttpRequest.handleRequest(HttpRequest.java:273)
at com.caucho.server.port.TcpConnection.run(TcpConnection.java:682)
at com.caucho.util.ThreadPool$Item.runTasks(ThreadPool.java:730)
at com.caucho.util.ThreadPool$Item.run(ThreadPool.java:649)
at java.lang.Thread.run(Thread.java:619)
20:57:02,495 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:02,496 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:72 - Setting tx as null and gtx as null
20:57:02,497 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:02,497 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:72 - Setting tx as Transaction[] and gtx as GlobalTransaction::1
20:57:02,572 [http--8080-0$11387974] TRACE ComponentRegistry:invocationsAllowed:877 - Testing if invocations are allowed.
20:57:03,084 [http--8080-0$11387974] TRACE TxInterceptor:afterCompletion:917 - calling aftercompletion for GlobalTransaction::1
20:57:03,085 [http--8080-0$11387974] TRACE TxInterceptor:afterCompletion:940 - Running rollback phase
20:57:03,085 [http--8080-0$11387974] TRACE TxInterceptor:runRollbackPhase:596 - running rollback for GlobalTransaction::1
20:57:03,086 [http--8080-0$11387974] TRACE CacheLoaderInterceptor:visitRollbackCommand:218 - Removing temporarily created nodes
20:57:03,205 [http--8080-0$11387974] TRACE CallInterceptor:visitRollbackCommand:91 - Suppressing invocation of method handleRollbackCommand.
20:57:03,206 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:cleanupLocks:387 - Releasing lock on [/user] for owner GlobalTransaction::1
20:57:03,207 [http--8080-0$11387974] TRACE MVCCLockManager:unlock:140 - Attempting to unlock /user
20:57:03,207 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:cleanupLocks:387 - Releasing lock on [/user/1:86] for owner GlobalTransaction::1
20:57:03,219 [http--8080-0$11387974] TRACE MVCCLockManager:unlock:140 - Attempting to unlock /user/1:86
20:57:03,220 [http--8080-0$11387974] TRACE MVCCLockingInterceptor:doAfterCall:355 - Nothing to do since there is a transaction in scope.
20:57:03,221 [http--8080-0$11387974] INFO TMCacheListener:onNodeTransactioncompleted:24 - : EventImpl{type=TRANSACTION_COMPLETED,pre=false, cache=org.jboss.cache.DataContainerImpl [0 nodes, 0 locks], modificationType=null, data=null, fqn=null, transaction=Transaction[], originLocal=true, targetFqn=null, successful=false, newView=null, buddyGroup=null}
20:57:03,228 [http--8080-0$11387974] TRACE TxInterceptor:handleCommitRollback:527 - Finished local commit/rollback method for GlobalTransaction::1
20:57:03,229 [http--8080-0$11387974] TRACE TxInterceptor:afterCompletion:942 - Finished rollback phase
20:57:03,230 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:71 - Setting up transactional context.
20:57:03,231 [http--8080-0$11387974] TRACE TxInterceptor:setTransactionalContext:72 - Setting tx as null and gtx as null
20:57:03,231 [http--8080-0$11387974] INFO UserManagerImpl:updateUser:91 - rolling back tx
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4206773#4206773
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4206773
17 years, 2 months
[JBoss Messaging] - Re: ClassCastException when casting ObjectMessage object
by chicken
I change the two lines to
if (message instanceof ObjectMessage) {
| System.out.println(CustomMessageBean.class.getClassLoader().toString() + " - " + CustomMessageBean.class.getProtectionDomain().getCodeSource().getLocation().toString());
| System.out.println(message.getClass().getClassLoader().toString() + " - " + message.getClass().getProtectionDomain().getCodeSource().getLocation().toString());
|
- Out put before the application is reloaded:
09:57:31,957 INFO [STDOUT] onMessage at JMS Receiver
| 09:57:31,965 INFO [STDOUT] WebappClassLoader
| delegate: false
| repositories:
| /WEB-INF/classes/
| ----------> Parent Classloader:
| java.net.FactoryURLClassLoader@1e5ed7b
| - file:/export/home/JBoss/jboss-eap-4.3/jboss-as/server/default/deploy/webapps.war/WEB-INF/classes/mail/CustomMessageBean.class
| 09:57:31,967 INFO [STDOUT] org.jboss.mx.loading.UnifiedClassLoader3@12bcd4b{ url=null ,addedOrder=2} - file:/export/home/JBoss/jboss-eap-4.3/jboss-as/server/default/lib/jboss-messaging-client.jar
|
- Out put after reloading application:
10:01:38,617 INFO [STDOUT] onMessage at JMS Receiver
| 10:01:38,629 INFO [STDOUT] WebappClassLoader
| delegate: false
| repositories:
| - file:/export/home/JBoss/jboss-eap-4.3/jboss-as/server/default/deploy/webapps.war/WEB-INF/classes/mail/CustomMessageBean.class
| 10:01:38,630 INFO [STDOUT] org.jboss.mx.loading.UnifiedClassLoader3@12bcd4b{ url=null ,addedOrder=2} - file:/export/home/JBoss/jboss-eap-4.3/jboss-as/server/default/lib/jboss-messaging-client.jar
| 10:01:38,634 INFO [STDOUT] ERROR: java.lang.ClassCastException: mail.CustomMessageBean
| 10:01:38,635 INFO [WebappClassLoader] Illegal access: this web application instance has been stopped already. Could not load org.apache.log4j.spi.ThrowableInformation. The eventual following stack trace is caused by an error thrown for debugging purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact.
| java.lang.IllegalStateException
| at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1244)
| at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1204)
| at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:319)
| at org.apache.log4j.spi.LoggingEvent.<init>(LoggingEvent.java:159)
| at org.apache.log4j.Category.forcedLog(Category.java:391)
| at org.apache.log4j.Category.error(Category.java:322)
| at mail.AsyncQueueReceiver.onMessage(AsyncQueueReceiver.java:150)
| at org.jboss.jms.client.container.ClientConsumer.callOnMessage(ClientConsumer.java:159)
| at org.jboss.jms.client.container.ClientConsumer$ListenerRunner.run(ClientConsumer.java:976)
| at org.jboss.messaging.util.JBMExecutor$TCLExecutor.run(JBMExecutor.java:99)
| at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89)
| at java.lang.Thread.run(Thread.java:595)
| 10:01:38,640 ERROR [ClientConsumer] Failed to deliver message
| java.lang.NoClassDefFoundError: org/apache/log4j/spi/ThrowableInformation
| at org.apache.log4j.spi.LoggingEvent.<init>(LoggingEvent.java:159)
| at org.apache.log4j.Category.forcedLog(Category.java:391)
| at org.apache.log4j.Category.error(Category.java:322)
| at mail.AsyncQueueReceiver.onMessage(AsyncQueueReceiver.java:150)
| at org.jboss.jms.client.container.ClientConsumer.callOnMessage(ClientConsumer.java:159)
| at org.jboss.jms.client.container.ClientConsumer$ListenerRunner.run(ClientConsumer.java:976)
| at org.jboss.messaging.util.JBMExecutor$TCLExecutor.run(JBMExecutor.java:99)
| at EDU.oswego.cs.dl.util.concurrent.QueuedExecutor$RunLoop.run(QueuedExecutor.java:89)
| at java.lang.Thread.run(Thread.java:595)
|
Beside the old error, I also get the error with Log4J :(
It is so strange!
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4206763#4206763
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4206763
17 years, 2 months