[ejabberd] Missing XMPP messages?

Sylvain Niles sylvain.niles at gmail.com
Sat Nov 13 02:02:39 MSK 2010


In answer to your inline question:

=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?]

No, this means that the process has sent an erlang message to the
ejabberd_s2s_out process with pid <0.482.0>, but there is no debug logging
of what is taking place once the message is received by that process.

If you're able to set up ssldump (http://www.rtfm.com/ssldump/) you can use
this to see what (if any) communication is happening between servers
including any errors that may not be logged. Alternatively if you can get
message debug logging on the other two hosts so you can see inbound s2s
messages and tie the logs together that would be helpful.

-Sylvain




On Fri, Nov 12, 2010 at 2:28 PM, Spencer Cheng <scheng at morphbius.com> wrote:

> 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>
>
>
> _______________________________________________
> ejabberd mailing list
> ejabberd at jabber.ru
> http://lists.jabber.ru/mailman/listinfo/ejabberd
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.jabber.ru/pipermail/ejabberd/attachments/20101112/3a1b8d20/attachment-0001.html>


More information about the ejabberd mailing list