[ejabberd] Missing XMPP messages?

Spencer Cheng scheng at morphbius.com
Sat Nov 13 01:28:27 MSK 2010


Hi,

I have set up a couple of ejabberd servers communicating with each other over TLS. Everything seems to work and messages are flowing between the ejabberd servers. Once in a while though one of the messages goes missing. I am not sure how to trace this. I have enabled debugging messages in the ejabberd conf file. (Ubuntu 10.04 LTS, ejabbered 2.1.5 binary installer) and can follow the message trail but I don't know where the message went. Here is a summary from the log with message content removed for brevity from the node that is sending the msg (dist.morphbius.com)

Can anyone give me a hint on how to trace this further? The log on the receiving node doesn't show any corresponding messages being received. 

Thanks in advance.

Regards,
Spencer 

------------Sending XMPP server---------------

=INFO REPORT==== 2010-11-11 21:06:12 ===
D(<0.366.0>:ejabberd_router:313) : route
        from {jid,"cdn_op","dist.morphbius.com","cdn","cdn_op",
		  "dist.morphbius.com","cdn"}
	to {jid,"cdn_op","leaf2.morphbius.com","cdn","cdn_op",
                "leaf2.morphbius.com","cdn"}
        packet {xmlelement,"message",
                [{"xml:lang","en"},
		 {"sn","294"},
		 {"to","cdn_op at leaf2.morphbius.com/cdn"},
                 {"type","normal"}],
                [{xmlelement,"body",[],
		  [{xmlcdata,<<"2010-11-11T21:05:54-05:00:Transferred">>}]},
		 {xmlelement,"transaction",
[...body of msg removed for sake of brevity...]
>>}]}]}]}


=INFO REPORT==== 2010-11-11 21:06:12 ===
D(<0.366.0>:ejabberd_s2s:251) : s2s manager
        from {jid,"cdn_op","dist.morphbius.com","cdn","cdn_op",
                  "dist.morphbius.com","cdn"}
        to {jid,"cdn_op","leaf2.morphbius.com","cdn","cdn_op",
                "leaf2.morphbius.com","cdn"}
        packet {xmlelement,"message",
                           [{"xml:lang","en"},{"sn",[...]},{[...],...},{...}],
                           [{xmlelement,[...],...},{xmlelement,...},{...}]}


=INFO REPORT==== 2010-11-11 21:06:12 ===
D(<0.366.0>:ejabberd_s2s:286) : Finding connection for {"dist.morphbius.com",
                                                        "leaf2.morphbius.com"}


=INFO REPORT==== 2010-11-11 21:06:12 ===
D(<0.366.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.488.0>


=INFO REPORT==== 2010-11-11 21:06:12 ===
D(<0.366.0>:ejabberd_s2s:255) : sending to process <0.488.0>

[ Does the above mean this message has been sent?]

=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.365.0>:ejabberd_receiver:320) : Received XML on stream = "<message sn='296' to='cdn_op at audit.morphbius.com/cdn' type='normal\
'><body>Starting transfer for 1059b190-d02f-012d-43ec-000c291809ee to leaf2.morphbius.com</body><transaction version='1.0' xmlns=\
'http://startimes.com.cn/ns/cdn/1.0'><log node='dist.morphbius.com'><severity>info</severity><eventStr>Starting transfer for 1059\
b [...more body removed for breivity...]


=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.365.0>:shaper:61) : State: {maxrate,1000,999.9576444042286,
                                    1289527576783112}, Size=2126
M=2125.909955817265, I=9.362


=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.366.0>:ejabberd_router:313) : route
        from {jid,"cdn_op","dist.morphbius.com","cdn","cdn_op",
                  "dist.morphbius.com","cdn"}
        to {jid,"cdn_op","audit.morphbius.com","cdn","cdn_op",
		"audit.morphbius.com","cdn"}
 
=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.366.0>:ejabberd_s2s:251) : s2s manager
        from {jid,"cdn_op","dist.morphbius.com","cdn","cdn_op",
                  "dist.morphbius.com","cdn"}
        to {jid,"cdn_op","audit.morphbius.com","cdn","cdn_op",
		"audit.morphbius.com","cdn"}
        packet {xmlelement,"message",
                           [{"xml:lang","en"},{"sn",[...]},{[...],...},{...}],
                           [{xmlelement,[...],...},{xmlelement,...},{...}]}


=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.366.0>:ejabberd_s2s:286) : Finding connection for {"dist.morphbius.com",
                                                        "audit.morphbius.com"}


=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.366.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.482.0>


=INFO REPORT==== 2010-11-11 21:06:16 ===
D(<0.366.0>:ejabberd_s2s:255) : sending to process <0.482.0>




More information about the ejabberd mailing list