[jboss-user] [JBoss Cache: Core Edition] - Re: Need helps with initial state transfer in production

jiafan1 do-not-reply at jboss.com
Mon Apr 14 20:43:52 EDT 2008 

The wired thing is that when the exception was thrown in stderr.log, the jbosscache.log did not have any exceptions and seems the node was still involved in replication actions. 

I am totally confused here,

1) Why did the new node react to replication requests at all before the initial state transfer finishes?

2) Why did it react to the replication requests after the web-app has already thrown exceptions? (Actually the error was from JBoss cache, right?. That is to say Jboss cache "start service" failed.)


  | 2008-04-08 17:00:27,981 (UDP mcast receiver)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 239.0.0.101:45567, src: 10.128.0.113:32837 (3 headers), size = 2082 bytes], headers are {NAKACK=[MSG, seqno=31112], MessageDispatcher=[Header: name=MessageDispatcher, type=REQ, id=1207688427993, rsp_expected=true], dest_mbrs=[10.128.0.111:32777, 10.128.0.112:32821, 10.128.0.114:32783], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:27,982 (UpHandler (NAKACK))  org.jgroups.protocols.pbcast.NAKACK.handleMessage
  |   DEBUG: 10.128.0.114:32783] received 10.128.0.113:32837#31112
  | 
  | 2008-04-08 17:00:27,982 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RequestCorrelator.handleRequest
  |   DEBUG: calling (org.jgroups.blocks.RpcDispatcher) with request 1207688427993
  | 
  | 2008-04-08 17:00:27,983 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RpcDispatcher.handle
  |   DEBUG: [sender=10.128.0.113:32837], method_call: _replicate(_put(null, /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at e31bc86d, true))
  | 
  | 2008-04-08 17:00:27,983 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.TxInterceptor.invoke
  |   DEBUG: (10.128.0.114:32783) call on method [_put(null, /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at e31bc86d, true)]
  | 
  | 2008-04-08 17:00:27,983 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke
  |   DEBUG: PessimisticLockInterceptor invoked for method _put(null, /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at e31bc86d, true)
  | 
  | 2008-04-08 17:00:27,984 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.PessimisticLockInterceptor.lock
  |   DEBUG: Attempting to lock node /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV for owner Thread[UpHandler (STATE_TRANSFER),5,main]
  | 
  | 2008-04-08 17:00:27,984 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 2008-04-08 17:00:27,984 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,984 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:27,985 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,985 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:27,985 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,986 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:27,986 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,986 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireWriteLock
  |   DEBUG: acquiring WL: fqn=/com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:27,986 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireWriteLock
  |   DEBUG: acquired WL: fqn=/com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
  | 
  | 2008-04-08 17:00:27,987 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.CallInterceptor.invoke
  |   DEBUG: Invoking method _put(null, /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at e31bc86d, true) on cache.
  | 
  | 2008-04-08 17:00:27,987 (UpHandler (STATE_TRANSFER))  org.jboss.cache.TreeCache._put
  |   DEBUG: _put(null, "/com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV", ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at e31bc86d)
  | 
  | 2008-04-08 17:00:27,987 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.invoke
  |   DEBUG: Invoking EvictionInterceptor
  | 
  | 2008-04-08 17:00:27,988 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.updateNode
  |   DEBUG: Updating node/element events with no tx
  | 
  | 2008-04-08 17:00:27,988 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.doEventUpdatesOnRegionManager
  |   DEBUG: Adding event EvictedEN[fqn=/com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV event=3 diff=1] to region at /com/jtv/core/st/
  | 
  | 2008-04-08 17:00:27,988 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.updateNode
  |   DEBUG: Finished updating node
  | 
  | 2008-04-08 17:00:27,989 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.invoke
  |   DEBUG: Finished invoking EvictionInterceptor
  | 
  | 2008-04-08 17:00:27,989 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.invoke
  |   DEBUG: Attempting to release locks on current thread.  Lock table is {Thread[UpHandler (STATE_TRANSFER),5,main]=[read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], write owner=Thread[UpHandler (STATE_TRANSFER),5,main]]}
  | 
  | 2008-04-08 17:00:27,989 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core/st/ST-181818-8J4Bzfnc0t08yd1X9TMMmsIEuGJdSHmY-JTV: write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
  | 
  | 2008-04-08 17:00:27,990 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core/st: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 2008-04-08 17:00:27,990 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,990 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,990 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:27,991 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.ReplicationInterceptor.invoke
  |   DEBUG: Non-tx crud meth
  | 
  | 2008-04-08 17:00:27,991 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RequestCorrelator.handleRequest
  |   DEBUG: sending rsp for 1207688427993 to 10.128.0.113:32837
  | 
  | 2008-04-08 17:00:27,992 (DownHandler (UNICAST))  org.jgroups.protocols.UNICAST.down
  |   DEBUG: 10.128.0.114:32783 --> DATA(10.128.0.113:32837: #22
  | 
  | 2008-04-08 17:00:27,992 (DownHandler (UDP))  org.jgroups.protocols.UDP.down
  |   DEBUG: sending msg to 10.128.0.113:32837 (src=10.128.0.114:32783), headers are {MessageDispatcher=[Header: name=MessageDispatcher, type=RSP, id=1207688427993, rsp_expected=false], UDP=[channel_name=JTVCORE-Cluster], UNICAST=[UNICAST: DATA, seqno=22]}
  | 
  | 2008-04-08 17:00:27,993 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.receive
  |   DEBUG: received (ucast) 67 bytes from 10.128.0.113:32837
  | 
  | 2008-04-08 17:00:27,994 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.113:32837 (2 headers), size = 0 bytes], headers are {UNICAST=[UNICAST: ACK, seqno=22], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:27,994 (UpHandler (UNICAST))  org.jgroups.protocols.UNICAST.handleAckReceived
  |   DEBUG: 10.128.0.114:32783 <-- ACK(10.128.0.113:32837: #22)
  | 2008-04-08 17:00:28,341 (TimeScheduler.Thread)  org.jgroups.protocols.FD.run
  |   DEBUG: sending are-you-alive msg to 10.128.0.111:32777 (own address=10.128.0.114:32783)
  | 
  | 2008-04-08 17:00:28,342 (DownHandler (UDP))  org.jgroups.protocols.UDP.down
  |   DEBUG: sending msg to 10.128.0.111:32777 (src=10.128.0.114:32783), headers are {FD=[FD: heartbeat], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:28,343 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.receive
  |   DEBUG: received (ucast) 69 bytes from 10.128.0.111:32777
  | 
  | 2008-04-08 17:00:28,343 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (2 headers), size = 0 bytes], headers are {UDP=[channel_name=JTVCORE-Cluster], FD=[FD: heartbeat ack]}
  | 
  | 2008-04-08 17:00:28,344 (UpHandler (FD))  org.jgroups.protocols.FD.up
  |   DEBUG: received ack from 10.128.0.111:32777
  | 
  | 2008-04-08 17:00:28,347 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.receive
  |   DEBUG: received (ucast) 57 bytes from 10.128.0.113:32837
  | 
  | 2008-04-08 17:00:28,348 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.113:32837 (2 headers), size = 0 bytes], headers are {UDP=[channel_name=JTVCORE-Cluster], FD=[FD: heartbeat]}
  | 
  | 2008-04-08 17:00:28,348 (UpHandler (FD))  org.jgroups.protocols.FD.up
  |   DEBUG: received are-you-alive from 10.128.0.113:32837, sending response
  | 2008-04-08 17:00:28,348 (DownHandler (UDP))  org.jgroups.protocols.UDP.down
  |   DEBUG: sending msg to 10.128.0.113:32837 (src=10.128.0.114:32783), headers are {FD=[FD: heartbeat ack], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:28,395 (UDP mcast receiver)  org.jgroups.protocols.UDP.receive
  |   DEBUG: received (mcast)2250 bytes from 10.128.0.112:32822
  | 
  | 2008-04-08 17:00:28,395 (UDP mcast receiver)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 239.0.0.101:45567, src: 10.128.0.112:32821 (3 headers), size = 2082 bytes], headers are {NAKACK=[MSG, seqno=123817], MessageDispatcher=[Header: name=MessageDispatcher, type=REQ, id=1207688428406, rsp_expected=true], dest_mbrs=[10.128.0.111:32777, 10.128.0.113:32837, 10.128.0.114:32783], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:28,396 (UpHandler (NAKACK))  org.jgroups.protocols.pbcast.NAKACK.handleMessage
  |   DEBUG: 10.128.0.114:32783] received 10.128.0.112:32821#123817
  | 
  | 2008-04-08 17:00:28,397 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RequestCorrelator.handleRequest
  |   DEBUG: calling (org.jgroups.blocks.RpcDispatcher) with request 1207688428406
  | 
  | 2008-04-08 17:00:28,398 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RpcDispatcher.handle
  |   DEBUG: [sender=10.128.0.112:32821], method_call: _replicate(_put(null, /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at 7ba38be2, true))
  | 
  | 2008-04-08 17:00:28,398 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.TxInterceptor.invoke
  |   DEBUG: (10.128.0.114:32783) call on method [_put(null, /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at 7ba38be2, true)]
  | 
  | 2008-04-08 17:00:28,399 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke
  |   DEBUG: PessimisticLockInterceptor invoked for method _put(null, /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at 7ba38be2, true)
  | 
  | 2008-04-08 17:00:28,399 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.PessimisticLockInterceptor.lock
  |   DEBUG: Attempting to lock node /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV for owner Thread[UpHandler (STATE_TRANSFER),5,main]
  | 
  | 2008-04-08 17:00:28,400 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:28,400 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,401 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:28,401 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,402 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:28,402 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,403 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquiring RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:28,403 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireReadLock
  |   DEBUG: acquired RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,404 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireWriteLock
  |   DEBUG: acquiring WL: fqn=/com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
  | 
  | 2008-04-08 17:00:28,404 (UpHandler (STATE_TRANSFER))  org.jboss.cache.Node.acquireWriteLock
  |   DEBUG: acquired WL: fqn=/com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
  | 2008-04-08 17:00:28,405 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.CallInterceptor.invoke
  |   DEBUG: Invoking method _put(null, /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at 7ba38be2, true) on cache.
  | 
  | 2008-04-08 17:00:28,405 (UpHandler (STATE_TRANSFER))  org.jboss.cache.TreeCache._put
  |   DEBUG: _put(null, "/com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV", ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket at 7ba38be2)
  | 
  | 2008-04-08 17:00:28,406 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.invoke
  |   DEBUG: Invoking EvictionInterceptor
  | 
  | 2008-04-08 17:00:28,406 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.updateNode
  |   DEBUG: Updating node/element events with no tx
  | 
  | 2008-04-08 17:00:28,407 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.doEventUpdatesOnRegionManager
  |   DEBUG: Adding event EvictedEN[fqn=/com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV event=3 diff=1] to region at /com/jtv/core/st/
  | 
  | 2008-04-08 17:00:28,407 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.updateNode
  |   DEBUG: Finished updating node
  | 
  | 2008-04-08 17:00:28,408 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.EvictionInterceptor.invoke
  |   DEBUG: Finished invoking EvictionInterceptor
  | 
  | 2008-04-08 17:00:28,408 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.invoke
  |   DEBUG: Attempting to release locks on current thread.  Lock table is {Thread[UpHandler (STATE_TRANSFER),5,main]=[read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], write owner=Thread[UpHandler (STATE_TRANSFER),5,main]]}
  | 
  | 2008-04-08 17:00:28,409 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core/st/ST-236922-4nakwXPVFHwF2RK1oLVDTMvmNmFdJyBj-JTV: write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
  | 
  | 2008-04-08 17:00:28,409 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core/st: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,410 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv/core: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,410 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com/jtv: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,411 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
  |   DEBUG: releasing lock for /com: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
  | 
  | 2008-04-08 17:00:28,411 (UpHandler (STATE_TRANSFER))  org.jboss.cache.interceptors.ReplicationInterceptor.invoke
  |   DEBUG: Non-tx crud meth
  | 
  | 2008-04-08 17:00:28,412 (UpHandler (STATE_TRANSFER))  org.jgroups.blocks.RequestCorrelator.handleRequest
  |   DEBUG: sending rsp for 1207688428406 to 10.128.0.112:32821
  | 
  | 2008-04-08 17:00:28,413 (DownHandler (UNICAST))  org.jgroups.protocols.UNICAST.down
  |   DEBUG: 10.128.0.114:32783 --> DATA(10.128.0.112:32821: #198
  | 
  | 2008-04-08 17:00:28,413 (DownHandler (UDP))  org.jgroups.protocols.UDP.down
  |   DEBUG: sending msg to 10.128.0.112:32821 (src=10.128.0.114:32783), headers are {MessageDispatcher=[Header: name=MessageDispatcher, type=RSP, id=1207688428406, rsp_expected=false], UDP=[channel_name=JTVCORE-Cluster], UNICAST=[UNICAST: DATA, seqno=198]}
  | 
  | 2008-04-08 17:00:28,415 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.receive
  |   DEBUG: received (ucast) 67 bytes from 10.128.0.112:32821
  | 
  | 2008-04-08 17:00:28,415 (UDP.UcastReceiverThread)  org.jgroups.protocols.UDP.handleIncomingMessage
  |   DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.112:32821 (2 headers), size = 0 bytes], headers are {UNICAST=[UNICAST: ACK, seqno=198], UDP=[channel_name=JTVCORE-Cluster]}
  | 
  | 2008-04-08 17:00:28,416 (UpHandler (UNICAST))  org.jgroups.protocols.UNICAST.handleAckReceived
  |   DEBUG: 10.128.0.114:32783 <-- ACK(10.128.0.112:32821: #198)
  | 

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

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

More information about the jboss-user mailing list