I have the logs but they're rather large so I put them here:
http://www.conduct.no/~bos/jc/. Can't say I find anything that explains the three
minute lag between the distribution of each node, but it seems that 11 does not
respond to 12s FD_SIMPLE ARE_YOU_ALIVE requests just as it the puts are beeing done.
11 also reports this 15 seconds after each put:
| 2004-08-31 15:11:08,964 DEBUG [GroupRequest] call did not execute correctly,
request is [GroupRequest:
| req_id=1093957853950
| members: 10.0.0.12:7890
| responses: null
| request_msg: [dst: <null>, src: <null> (2 headers), size = 1301 bytes]
| rsp_mode: 2
| done: false
| timeout: 15000
| expected_mbrs: 0
| ]
| 2004-08-31 15:11:08,972 DEBUG [RpcDispatcher] responses: [sender=10.0.0.12:7890,
retval=null, received=false, suspected=false]
|
When 12 finally recives the first node this is what's in the log:
| 2004-08-31 15:13:23,728 DEBUG [TCP] received msg [dst: 10.0.0.12:7890, src:
10.0.0.11:7890 (3 headers), size = 1301 bytes]
| 2004-08-31 15:13:23,729 DEBUG [NAKACK] [10.0.0.12:7890] received
<10.0.0.11:7890#27>
| 2004-08-31 15:13:23,729 DEBUG [NAKACK] receiver window for 10.0.0.11:7890 is:
received_msgs: [27 - 27 (size=1, missing=0)], delivered_msgs: [0 - 26]
| 2004-08-31 15:13:23,729 DEBUG [STABLE] stable task started; num_gossip_runs=3,
max_gossip_runs=3
| 2004-08-31 15:13:23,730 DEBUG [RequestCorrelator] header is [Header:
name=MessageDispatcher, type=REQ, id=1093957853950, rsp_expected=true],
dest_mbrs=[10.0.0.12:7890]
| 2004-08-31 15:13:23,730 DEBUG [RequestCorrelator] calling
(org.jgroups.blocks.RpcDispatcher) with request 1093957853950
| 2004-08-31 15:13:23,771 DEBUG [RpcDispatcher] [sender=10.0.0.11:7890],
method_call: _replicate(_put(null,
| /Login
Ticket/ioCAix7SAAAA-rT4IndfEPtZC2RvW7*mArS0vjILMssBaocG-5eFjN1VCBOo1Uk9SRhRUWiEHKUGXWAWhcM,
{TicketType=Login Ticket
| , TimeToLive=1093969373810, [EMAIL PROTECTED], Principal=demo}, true))
| 2004-08-31 15:13:23,775 DEBUG [RequestCorrelator] sending rsp for 1093957853950 to
10.0.0.11:7890
| 2004-08-31 15:13:23,776 DEBUG [TCP] dest=10.0.0.11:7890, hdrs:
| TCP: [TCP:group_addr=Moria-Cluster]
| MessageDispatcher: [Header: name=MessageDispatcher, type=RSP, id=1093957853950,
rsp_expected=false]
|
and on 11 at the same moment:
| 2004-08-31 15:13:23,742 DEBUG [NAKACK] [10.0.0.11:7890] received
<10.0.0.11:7890#27>
| 2004-08-31 15:13:23,742 DEBUG [TCP] dest=10.0.0.12:7890, hdrs:
| NAKACK: [NAKACK: MSG, seqno=27, range=null]
| TCP: [TCP:group_addr=Moria-Cluster]
| MessageDispatcher: [Header: name=MessageDispatcher, type=REQ, id=1093957853950,
rsp_expected=true], dest_mbrs=[10.0.0.12:7890]
|
| 2004-08-31 15:13:23,742 DEBUG [NAKACK] receiver window for 10.0.0.11:7890 is:
received_msgs: [27 - 27 (size=1, missing=0)], delivered_msgs: [0 - 26]
| 2004-08-31 15:13:23,743 DEBUG [RequestCorrelator] discarded request from
10.0.0.11:7890 as we are not part of destination list
| (local_addr=10.0.0.11:7890, hdr=[Header: name=MessageDispatcher, type=REQ,
id=1093957853950, rsp_expected=true], dest_mbrs=[10.0.0.12:7890])
| 2004-08-31 15:13:23,743 DEBUG [TCP] dest=10.0.0.21:7890, hdrs:
| TCP: [TCP:group_addr=Moria-Cluster]
| TCPPING: [PING: type=GET_MBRS_REQ, arg=null]
|
| 2004-08-31 15:13:23,743 DEBUG [TCP] dest=10.0.0.21:7891, hdrs:
| TCP: [TCP:group_addr=Moria-Cluster]
| TCPPING: [PING: type=GET_MBRS_REQ, arg=null]
|
| 2004-08-31 15:13:23,745 DEBUG [TCP] received msg [dst: 10.0.0.11:7890, src:
10.0.0.12:7890 (2 headers), size = 0 bytes]
| 2004-08-31 15:13:23,746 INFO [FD_SIMPLE] received I_AM_ALIVE response from
10.0.0.12:7890
| 2004-08-31 15:13:23,747 DEBUG [TCP] received msg [dst: 10.0.0.11:7890, src:
10.0.0.12:7890 (2 headers), size = 0 bytes]
| 2004-08-31 15:13:23,747 DEBUG [TCP] received msg [dst: 10.0.0.11:7890, src:
10.0.0.12:7890 (2 headers), size = 0 bytes]
| 2004-08-31 15:13:23,748 INFO [FD_SIMPLE] received I_AM_ALIVE response from
10.0.0.12:7890
| 2004-08-31 15:13:23,748 DEBUG [TCP] received msg [dst: 10.0.0.11:7890, src:
10.0.0.12:7890 (2 headers), size = 0 bytes]
| 2004-08-31 15:13:23,748 INFO [FD_SIMPLE] received I_AM_ALIVE response from
10.0.0.12:7890
| 2004-08-31 15:13:23,799 DEBUG [TCP] received msg [dst: 10.0.0.11:7890, src:
10.0.0.12:7890 (2 headers), size = 5 bytes]
| 2004-08-31 15:13:23,799 DEBUG [RequestCorrelator] header is [Header:
name=MessageDispatcher, type=RSP, id=1093957853950, rsp_expected=false]
| 2004-08-31 15:13:23,819 INFO [FD_SIMPLE] sending ARE_YOU_ALIVE message to
10.0.0.12:7890, counters are
| 10.0.0.12:7890: 0
| 10.0.0.11:7890: 0
|
None of this tell me anything really, but maybe you can read something from it.
View the original post :
http://www.jboss.org/index.html?module=bb&op=viewtopic&p=3847070#3847070
Reply to the post :
http://www.jboss.org/index.html?module=bb&op=posting&mode=reply&p=3847070
-------------------------------------------------------
This SF.Net email is sponsored by BEA Weblogic Workshop
FREE Java Enterprise J2EE developer tools!
Get your free copy of BEA WebLogic Workshop 8.1 today.
http://ads.osdn.com/?ad_id=5047&alloc_id=10808&op=click
_______________________________________________
JBoss-Development mailing list
[EMAIL PROTECTED]
https://lists.sourceforge.net/lists/listinfo/jboss-development
