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

Monday, 14 April 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@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@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@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@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@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@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@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@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@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@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#...

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

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

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