[ejabberd] Setting up ejabberd MUC to supplement Google Talk

Bri Hatch bri at ifokr.org
Tue Sep 13 03:32:38 MSD 2011


About Fri, Sep 9, 2011 at 9:27 AM, Bri Hatch <bri at ifokr.org> rambled:

> It's "working" for certain values of work currently.   I'm getting a
> lot of 503 errors when speaking in the MUC w/ my Google Talk account.
> That said, I'm also getting 503 errors when speaking in the MUC w/ my
> jabber.org account.   Will spend some time looking through the logs
> to see what it's seeing.

So things are working fine with my "interesting" setup, but I am getting
503s about 1/5th of the time.

I snagged individual log entries to compare the succesful logs and
failures.

In each case, moments apart, I sent "Testing 1 2 3" in the room-name
room of the conference.example.com conference whilst connected from
the PERSON_ONE at example.com Google Apps xmpp user, using Pidgin.  In
the room as also a PERSON_TWO who wasn't saying anything at the time.
That said, I could reproduce this even when I'm the only one in the
room, or if I was in the room from a @jabber.org account, rather
than a Google Apps account, it's not specific to the Google Apps
account.

The main difference I can see is that in the failure case (/tmp/bad.txt)
it tries a 'local route', whereas in the successful case (/tmp/good.txt)
it does a 'route' - short snippet for comparison:


Bad version:

  D(<0.3192.0>:ejabberd_local:286) : local route
	  from {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                    "example.com","Molybdenum1C7D4D3F"}
	  to {jid,"room-name","conference.example.com",[],"room-name",
                  "conference.example.com",[]}
	  packet {xmlelement,"message",
                           [{"type","groupchat"},

Good version:
  =INFO REPORT==== 2011-09-12 23:12:22 ===
  D(<0.289.0>:mod_muc:507) : MUC: send to process <0.3335.0>


  =INFO REPORT==== 2011-09-12 23:12:22 ===
  D(<0.3335.0>:ejabberd_router:301) : route
	  from {jid,"room-name","conference.example.com","PERSON_ONE","room-name",
                    "conference.example.com","PERSON_ONE"}
	  to {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                  "example.com","Molybdenum1C7D4D3F"}
	  packet {xmlelement,"message",
                     [{"type","groupchat"},
                      {"id","purple94666554"},
                      {"to","room-name at conference.example.com"},
                      {"from","PERSON_ONE at example.com/Molybdenum1C7D4D3F"}],
                     [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]}]}



Full logs of these attempts are attached.



-- 
Bri Hatch, Systems and Security Engineer. http://www.ifokr.org/bri/

Reegen: "Daddy's the King, Mama's the Queen, I'm the princess,
        and you're both little princesses!"
Lainee: "No wanna be princess!"
Reegen: "Ok, then you're the maid."
-------------- next part --------------
                                                         "example.com"}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:255) : sending to process <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3191.0>:ejabberd_receiver:320) : Received XML on stream = "<message type=\"groupchat\" id=\"purple94666554\" to=\"room-name at conference.example.com\" from=\"PERSON_ONE at example.com/Molybdenum1C7D4D3F\"><body>Testing 1 2 3</body></message>"

=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3191.0>:shaper:61) : State: {maxrate,50000,99.71488023802662,
                                     1315869129226347}, Size=164
M=1.6416369563249427, I=13155.585


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3192.0>:ejabberd_router:301) : route
	from {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                  "example.com","Molybdenum1C7D4D3F"}
	to {jid,"room-name","conference.example.com",[],"room-name",
                "conference.example.com",[]}
	packet {xmlelement,"message",
                   [{"type","groupchat"},
                    {"id","purple94666554"},
                    {"to","room-name at conference.example.com"},
                    {"from","PERSON_ONE at example.com/Molybdenum1C7D4D3F"}],
                   [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]}]}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.289.0>:mod_muc:507) : MUC: send to process <0.3335.0>


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_router:301) : route
	from {jid,"room-name","conference.example.com","PERSON_ONE","room-name",
                  "conference.example.com","PERSON_ONE"}
	to {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                "example.com","Molybdenum1C7D4D3F"}
	packet {xmlelement,"message",
                   [{"type","groupchat"},
                    {"id","purple94666554"},
                    {"to","room-name at conference.example.com"},
                    {"from","PERSON_ONE at example.com/Molybdenum1C7D4D3F"}],
                   [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]}]}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:251) : s2s manager
	from {jid,"room-name","conference.example.com","PERSON_ONE","room-name",
                  "conference.example.com","PERSON_ONE"}
	to {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                "example.com","Molybdenum1C7D4D3F"}
	packet {xmlelement,"message",
                           [{"type","groupchat"},
                            {"id",[...]},
                            {[...],...},
                            {...}],
                           [{xmlelement,[...],...}]}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:286) : Finding connection for {"conference.example.com",
                                                         "example.com"}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:255) : sending to process <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_router:301) : route
	from {jid,"room-name","conference.example.com","PERSON_ONE","room-name",
                  "conference.example.com","PERSON_ONE"}
	to {jid,"PERSON_TWO","example.com","Adium99328E97","PERSON_TWO",
                "example.com","Adium99328E97"}
	packet {xmlelement,"message",
                   [{"type","groupchat"},
                    {"id","purple94666554"},
                    {"to","room-name at conference.example.com"},
                    {"from","PERSON_ONE at example.com/Molybdenum1C7D4D3F"}],
                   [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]}]}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:251) : s2s manager
	from {jid,"room-name","conference.example.com","PERSON_ONE","room-name",
                  "conference.example.com","PERSON_ONE"}
	to {jid,"PERSON_TWO","example.com","Adium99328E97","PERSON_TWO",
                "example.com","Adium99328E97"}
	packet {xmlelement,"message",
                           [{"type","groupchat"},
                            {"id",[...]},
                            {[...],...},
                            {...}],
                           [{xmlelement,[...],...}]}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:286) : Finding connection for {"conference.example.com",
                                                         "example.com"}


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:22 ===
D(<0.3335.0>:ejabberd_s2s:255) : sending to process <0.3156.0>

-------------- next part --------------


=INFO REPORT==== 2011-09-12 23:12:06 ===
D(<0.3191.0>:ejabberd_receiver:320) : Received XML on stream = " "

=INFO REPORT==== 2011-09-12 23:12:06 ===
D(<0.3191.0>:shaper:61) : State: {maxrate,50000,278.53516390171535,
                                     1315869118409352}, Size=1
M=0.010027931314923974, I=8088.213


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3191.0>:ejabberd_receiver:320) : Received XML on stream = "<message type=\"groupchat\" id=\"purple94666553\" to=\"room-name at conference.example.com\" from=\"PERSON_ONE at example.com/Molybdenum1C7D4D3F\"><body>Testing 1 2 3</body></message>"

=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3191.0>:shaper:61) : State: {maxrate,50000,139.32940020371066,
                                     1315869126497578}, Size=164
M=1.6422881902851407, I=2728.746


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_router:301) : route
	from {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                  "example.com","Molybdenum1C7D4D3F"}
	to {jid,"room-name","conference.example.com",[],"room-name",
                "conference.example.com",[]}
	packet {xmlelement,"message",
                   [{"type","groupchat"},
                    {"id","purple94666553"},
                    {"to","room-name at conference.example.com"},
                    {"from","PERSON_ONE at example.com/Molybdenum1C7D4D3F"}],
                   [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]}]}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_local:286) : local route
	from {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                  "example.com","Molybdenum1C7D4D3F"}
	to {jid,"room-name","conference.example.com",[],"room-name",
                "conference.example.com",[]}
	packet {xmlelement,"message",
                           [{"type","groupchat"},
                            {"id",[...]},
                            {[...],...},
                            {...}],
                           [{xmlelement,[...],...}]}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_sm:394) : session manager
	from {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                  "example.com","Molybdenum1C7D4D3F"}
	to {jid,"room-name","conference.example.com",[],"room-name",
                "conference.example.com",[]}
	packet {xmlelement,"message",
                           [{"type","groupchat"},
                            {"id",[...]},
                            {[...],...},
                            {...}],
                           [{xmlelement,[...],...}]}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_router:301) : route
	from {jid,"room-name","conference.example.com",[],"room-name",
                  "conference.example.com",[]}
	to {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                "example.com","Molybdenum1C7D4D3F"}
	packet {xmlelement,"message",
                   [{"type","error"},
                    {"to","PERSON_ONE at example.com/Molybdenum1C7D4D3F"},
                    {"from","room-name at conference.example.com"},
                    {"id","purple94666553"}],
                   [{xmlelement,"body",[],[{xmlcdata,<<"Testing 1 2 3">>}]},
                    {xmlelement,"error",
                        [{"code","503"},{"type","cancel"}],
                        [{xmlelement,"service-unavailable",
                             [{"xmlns","urn:ietf:params:xml:ns:xmpp-stanzas"}],
                             []}]}]}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:251) : s2s manager
	from {jid,"room-name","conference.example.com",[],"room-name",
                  "conference.example.com",[]}
	to {jid,"PERSON_ONE","example.com","Molybdenum1C7D4D3F","PERSON_ONE",
                "example.com","Molybdenum1C7D4D3F"}
	packet {xmlelement,"message",
                           [{"type","error"},{"to",[...]},{[...],...},{...}],
                           [{xmlelement,[...],...},{xmlelement,...}]}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:286) : Finding connection for {"conference.example.com",
                                                         "example.com"}


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:336) : Using ejabberd_s2s_out <0.3156.0>


=INFO REPORT==== 2011-09-12 23:12:09 ===
D(<0.3192.0>:ejabberd_s2s:255) : sending to process <0.3156.0>



More information about the ejabberd mailing list