[ejabberd] I/O error on client and worker crash on server (2.0.5)

Antoni Grzymala antoni at chopin.edu.pl
Sun Apr 4 13:50:17 MSD 2010


Hi,

one of the users of my server is getting a disconnect with an "I/O
error" reported by client. He's using the Meebo client for Android. He
had a similar error with some older release of Pidgin (not getting it
with a newer one). This is soon after connection, perhaps when first
status information is being sent.

I'm attaching the logs from my server. I'm running 2.0.5 on Gentoo
(Gentoo version 2.0.5-r3); any suggestions welcome. If more info
(configs and such) are needed, I'll gladly provide.

Best regards,

-- 
[a]
-------------- next part --------------
=INFO REPORT==== 2010-04-03 10:16:09 ===
I(<0.5673.0>:ejabberd_c2s:1306) : ({socket_state,tls,{tlssock,#Port<0.15553>,#Port<0.15555>},<0.5672.0>}) Close session for automaciej at chopin.edu.pl/Meebo

=ERROR REPORT==== 2010-04-03 10:16:09 ===
** State machine <0.5673.0> terminating
** Last message in was {route,
                        {jid,"automaciej","chopin.edu.pl",[],"automaciej",
                         "chopin.edu.pl",[]},
                        {jid,"automaciej","chopin.edu.pl","Meebo",
                         "automaciej","chopin.edu.pl","Meebo"},
                        {xmlelement,"iq",
                         [{"id","purplebbabe77b"},{"type","error"}],
                         [{function_clause,
                           [{lists,foreach,
                             [#Fun<node_default.0.7620314>,
                              {'EXIT',
                               {aborted,
                                {function_clause,
                                 [{nodetree_default,
                                   '-get_subnodes_tree/2-fun-0-',
                                   [{pubsub_node,
                                     {"pubsub.chopin.edu.pl",["home"]},
                                     {"pubsub.chopin.edu.pl",[]},
                                     {nodeinfo,[],[],
                                      {dict,1,16,16,8,80,48,
                                       {[],[],[],[],[],[],[],[],[],[],[],[],
                                        [],[],[],[]},
                                       {{[[{[],"pubsub.chopin.edu.pl",[]}|
                                           {entity,owner,none}]],
                                         [],[],[],[],[],[],[],[],[],[],[],[],
                                         [],[],[]}}}}},
                                    [],
                                    "http://www.xmpp.org/extensions/xep-0084.html#ns-metadata",
                                    {"automaciej","chopin.edu.pl",[]}]},
                                  {lists,foldl,3},
                                  {mnesia,do_foldl,8},
                                  {mnesia,foldl,6},
                                  {nodetree_default,delete_node,2},
                                  {mod_pubsub,tree_call,3},
                                  {mod_pubsub,'-delete_node/3-fun-0-',4},
                                  {mnesia_tm,apply_fun,3}]}}}]},
                            {node_default,delete_node,2},
                            {node_pep,delete_node,2},
                            {mod_pubsub,node_call,3},
                            {mnesia_tm,apply_fun,3},
                            {mnesia_tm,execute_transaction,5},
                            {mod_pubsub,transaction,2},
                            {mod_pubsub,delete_node,3}]},
                          {xmlelement,"pubsub",
                           [{"xmlns",
                             "http://jabber.org/protocol/pubsub#owner"}],
                           [{xmlelement,"delete",
                             [{"node",
                               "http://www.xmpp.org/extensions/xep-0084.html#ns-metadata"}],
                             []}]}]}}
** When State == session_established
**      Data  == {state,
                     {socket_state,tls,
                         {tlssock,#Port<0.15553>,#Port<0.15555>},
                         <0.5672.0>},
                     ejabberd_socket,#Ref<0.0.0.88135>,"611246543",
                     {sasl_state,"jabber","chopin.edu.pl",[],
                         #Fun<ejabberd_c2s.1.13692600>,
                         #Fun<ejabberd_c2s.2.130912430>,cyrsasl_digest,
                         {state,5,"3015193964","automaciej",[],
                             #Fun<ejabberd_c2s.1.13692600>,
                             ejabberd_auth_internal,"chopin.edu.pl"}},
                     c2s,c2s_shaper,false,true,false,true,
                     [verify_none,{certfile,"/etc/jabber/chopin.edu.pl.pem"}],
                     true,
                     {jid,"automaciej","chopin.edu.pl","Meebo","automaciej",
                         "chopin.edu.pl","Meebo"},
                     "automaciej","chopin.edu.pl","Meebo",
                     {{1270,282568,341123},<0.5673.0>},
                     {6,
                      {{"luksferka","gmail.com",[]},
                       {{"automaciej","chopin.edu.pl",[]},
                        {{"antoni","chopin.edu.pl",[]},nil,nil},
                        {{"lucyna.palzewicz","gmail.com",[]},nil,nil}},
                       {{"tatar","chopin.edu.pl",[]},
                        {{"mikolaj","chopin.edu.pl",[]},nil,nil},
                        nil}}},
                     {7,
                      {{"luksferka","gmail.com",[]},
                       {{"automaciej","chopin.edu.pl",[]},
                        {{"antoni","chopin.edu.pl",[]},nil,nil},
                        {{"lucyna.palzewicz","gmail.com",[]},nil,nil}},
                       {{"tatar","chopin.edu.pl",[]},
                        {{"mikolaj","chopin.edu.pl",[]},nil,nil},
                        {{"tomekd","chopin.edu.pl",[]},nil,nil}}}},
                     {7,
                      {{"luksferka","gmail.com",[]},
                       {{"automaciej","chopin.edu.pl",[]},
                        {{"antoni","chopin.edu.pl",[]},nil,nil},
                        {{"lucyna.palzewicz","gmail.com",[]},nil,nil}},
                       {{"tatar","chopin.edu.pl",[]},
                        {{"mikolaj","chopin.edu.pl",[]},nil,nil},
                        {{"tomekd","chopin.edu.pl",[]},nil,nil}}}},
                     {0,nil},
                     {xmlelement,"presence",[],
                         [{xmlelement,"status",[],
                              [{xmlcdata,<<"Amsterdam ">>}]},
                          {xmlelement,"priority",[],[{xmlcdata,<<"1">>}]},
                          {xmlelement,"c",
                              [{"xmlns","http://jabber.org/protocol/caps"},
                               {"node","http://pidgin.im/"},
                               {"hash","sha-1"},
                               {"ver","aprED9Gr9SNJfy5uKi3DUNyh0KY="}],
                              []},
                          {xmlelement,"x",
                              [{"xmlns","vcard-temp:x:update"}],
                              []}]},
                     undefined,undefined,false,
                     {userlist,none,[]},
                     c2s_tls,ejabberd_auth_internal,
                     {{0,0,0,0,0,65535,19058,6251},38384},
                     []}
** Reason for termination =
** {{case_clause,
     {function_clause,
      [{lists,foreach,
        [#Fun<node_default.0.7620314>,
         {'EXIT',
          {aborted,
           {function_clause,
            [{nodetree_default,'-get_subnodes_tree/2-fun-0-',
              [{pubsub_node,
                {"pubsub.chopin.edu.pl",["home"]},
                {"pubsub.chopin.edu.pl",[]},
                {nodeinfo,[],[],
                 {dict,1,16,16,8,80,48,
                  {[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]},
                  {{[[{[],"pubsub.chopin.edu.pl",[]}|{entity,owner,none}]],
                    [],[],[],[],[],[],[],[],[],[],[],[],[],[],[]}}}}},
               [],"http://www.xmpp.org/extensions/xep-0084.html#ns-metadata",
               {"automaciej","chopin.edu.pl",[]}]},
             {lists,foldl,3},
             {mnesia,do_foldl,8},
             {mnesia,foldl,6},
             {nodetree_default,delete_node,2},
             {mod_pubsub,tree_call,3},
             {mod_pubsub,'-delete_node/3-fun-0-',4},
             {mnesia_tm,apply_fun,3}]}}}]},
       {node_default,delete_node,2},
       {node_pep,delete_node,2},
       {mod_pubsub,node_call,3},
       {mnesia_tm,apply_fun,3},
       {mnesia_tm,execute_transaction,5},
       {mod_pubsub,transaction,2},
       {mod_pubsub,delete_node,3}]}},
    [{xml,element_to_string,1},
     {xml,'-element_to_string/1-lc$^0/1-0-',1},
     {xml,element_to_string,1},
     {ejabberd_c2s,handle_info,3},
     {gen_fsm,handle_msg,7},
     {proc_lib,init_p_do_apply,3}]}

==> sasl.log <==

=CRASH REPORT==== 3-Apr-2010::10:16:09 ===
  crasher:
    initial call: ejabberd_c2s:init/1
    pid: <0.5673.0>
    registered_name: []
    exception exit: {{case_clause,
                      {function_clause,
                       [{lists,foreach,
                         [#Fun<node_default.0.7620314>,
                          {'EXIT',
                           {aborted,
                            {function_clause,
                             [{nodetree_default,
                               '-get_subnodes_tree/2-fun-0-',
                               [{pubsub_node,
                                 {"pubsub.chopin.edu.pl",["home"]},
                                 {"pubsub.chopin.edu.pl",[]},
                                 {nodeinfo,[],[],
                                  {dict,1,16,16,8,80,48,
                                   {[],[],[],[],[],[],[],[],[],[],[],[],[],[],
                                    [],[]},
                                   {{[[{[],"pubsub.chopin.edu.pl",[]}|
                                       {entity,owner,none}]],
                                     [],[],[],[],[],[],[],[],[],[],[],[],[],
                                     [],[]}}}}},
                                [],
                                "http://www.xmpp.org/extensions/xep-0084.html#ns-metadata",
                                {"automaciej","chopin.edu.pl",[]}]},
                              {lists,foldl,3},
                              {mnesia,do_foldl,8},
                              {mnesia,foldl,6},
                              {nodetree_default,delete_node,2},
                              {mod_pubsub,tree_call,3},
                              {mod_pubsub,'-delete_node/3-fun-0-',4},
                              {mnesia_tm,apply_fun,3}]}}}]},
                        {node_default,delete_node,2},
                        {node_pep,delete_node,2},
                        {mod_pubsub,node_call,3},
                        {mnesia_tm,apply_fun,3},
                        {mnesia_tm,execute_transaction,5},
                        {mod_pubsub,transaction,2},
                        {mod_pubsub,delete_node,3}]}},
                     [{xml,element_to_string,1},
                      {xml,'-element_to_string/1-lc$^0/1-0-',1},
                      {xml,element_to_string,1},
                      {ejabberd_c2s,handle_info,3},
                      {gen_fsm,handle_msg,7},
                      {proc_lib,init_p_do_apply,3}]}
      in function  gen_fsm:terminate/7
    ancestors: [ejabberd_c2s_sup,ejabberd_sup,<0.37.0>]
    messages: []
    links: [#Port<0.15555>,<0.263.0>]
    dictionary: []
    trap_exit: false
    status: running
    heap_size: 6765
    stack_size: 24
    reductions: 60631
  neighbours:

=SUPERVISOR REPORT==== 3-Apr-2010::10:16:09 ===
     Supervisor: {local,ejabberd_c2s_sup}
     Context:    child_terminated
     Reason:     {{case_clause,
                   {function_clause,
                    [{lists,foreach,
                      [#Fun<node_default.0.7620314>,
                       {'EXIT',
                        {aborted,
                         {function_clause,
                          [{nodetree_default,'-get_subnodes_tree/2-fun-0-',
                            [{pubsub_node,
                              {"pubsub.chopin.edu.pl",["home"]},
                              {"pubsub.chopin.edu.pl",[]},
                              {nodeinfo,[],[],
                               {dict,1,16,16,8,80,48,
                                {[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],
                                 []},
                                {{[[{[],"pubsub.chopin.edu.pl",[]}|
                                    {entity,owner,none}]],
                                  [],[],[],[],[],[],[],[],[],[],[],[],[],[],
                                  []}}}}},
                             [],
                             "http://www.xmpp.org/extensions/xep-0084.html#ns-metadata",
                             {"automaciej","chopin.edu.pl",[]}]},
                           {lists,foldl,3},
                           {mnesia,do_foldl,8},
                           {mnesia,foldl,6},
                           {nodetree_default,delete_node,2},
                           {mod_pubsub,tree_call,3},
                           {mod_pubsub,'-delete_node/3-fun-0-',4},
                           {mnesia_tm,apply_fun,3}]}}}]},
                     {node_default,delete_node,2},
                     {node_pep,delete_node,2},
                     {mod_pubsub,node_call,3},
                     {mnesia_tm,apply_fun,3},
                     {mnesia_tm,execute_transaction,5},
                     {mod_pubsub,transaction,2},
                     {mod_pubsub,delete_node,3}]}},
                  [{xml,element_to_string,1},
                   {xml,'-element_to_string/1-lc$^0/1-0-',1},
                   {xml,element_to_string,1},
                   {ejabberd_c2s,handle_info,3},
                   {gen_fsm,handle_msg,7},
                   {proc_lib,init_p_do_apply,3}]}
     Offender:   [{pid,<0.5673.0>},
                  {name,undefined},
                  {mfa,
                      {ejabberd_c2s,start_link,
                          [{ejabberd_socket,
                               {socket_state,gen_tcp,#Port<0.15553>,
                                   <0.5672.0>}},
                           [inet6,
                            {certfile,"/etc/jabber/chopin.edu.pl.pem"},
                            starttls,
                            {access,c2s},
                            {shaper,c2s_shaper},
                            {max_stanza_size,65536}]]}},
                  {restart_type,temporary},
                  {shutdown,brutal_kill},
                  {child_type,worker}]


==> ejabberd.log <==

=INFO REPORT==== 2010-04-03 10:16:24 ===
I(<0.274.0>:ejabberd_listener:116) : (#Port<0.15558>) Accepted connection {{0,0,0,0,0,65535,19058,6251},16978} -> {{0,0,0,0,0,65535,50107,21242},5222}

=INFO REPORT==== 2010-04-03 10:16:25 ===
I(<0.5676.0>:ejabberd_c2s:692) : ({socket_state,tls,{tlssock,#Port<0.15558>,#Port<0.15560>},<0.5675.0>}) Accepted authentication for automaciej

=INFO REPORT==== 2010-04-03 10:16:25 ===
I(<0.5676.0>:ejabberd_c2s:804) : ({socket_state,tls,{tlssock,#Port<0.15558>,#Port<0.15560>},<0.5675.0>}) Opened session for automaciej at chopin.edu.pl/Meebo


More information about the ejabberd mailing list