[ejabberd] Forbidden Session and TLS error

Aaron Martinez willwrk4u at hotmail.com
Wed Jul 10 01:52:16 MSK 2013


I posted this also to the forum but the realized it doesn't look like it's
getting much traffic.

Hi All,

I have set up ejabberd 2.1.12 and having a heck of a time getting a client
connected.  I am using auth_ldap and I finally got that working I think
because of the following log message:

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
I(<0.347.0>:ejabberd_c2s:632) :
({socket_state,tls,{tlssock,#Port<0.4045>,#Port<0.4047>},<0.346.0>})
Accepted authentication for apmartinez by ejabberd_a
uth_ldap

After this however I try to connect from my client and get the error "error
initializing session".  My xmmp client is pidgin and I have the debug log at
the bottom of the email under the configs and debug logs from ejabberd.

The two errors that I seem to be getting are the following:
1.   "I(<0.347.0>:ejabberd_c2s:978) :
({socket_state,tls,{tlssock,#Port<0.4045>,#Port<0.4047>},<0.346.0>})
Forbidden session for apmartinez at company.com/Work"
2.   "D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<iq
type='error' id='purple668ff4b4'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/><error code='405'
type='cancel'><not-allowed
xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>">>"

and randomly I will see this:
3.  "D(<0.347.0>:ejabberd_socket:178) : Error in tls:send: {'EXIT',
                                                       {badarg,
                                                        [{erlang,
                                                          port_control,
                                                          [#Port<0.4047>,4,
                                                           "</stream:stream>"],
                                                          []},
                                                         {tls,send,2,
                                                          [{file,"tls.erl"},
                                                           {line,216}]},
                                                         {ejabberd_socket,
                                                          send,2,
                                                          [{file,
                                                           
"ejabberd_socket.erl"},
                                                           {line,171}]},
                                                         {ejabberd_c2s,
                                                          wait_for_session,2,
                                                          [{file,
                                                            "ejabberd_c2s.erl"},
                                                           {line,993}]},
                                                         {p1_fsm,handle_msg,
                                                          10,
                                                          [{file,"p1_fsm.erl"},
                                                           {line,544}]},
                                                         {proc_lib,
                                                          init_p_do_apply,3,
                                                          [{file,
                                                            "proc_lib.erl"},
                                                           {line,227}]}]}}"

Config and log messages below, please let me know if there is anything
further needed.

I'm really at a loss and any and all are help greatly appreciated.

Thanks in advance,

Aaron Martinez

Config:

{loglevel, 5}.

%%{fqdn,        "jabber.company.com"}.


{hosts, ["company.com"]}.

{acl, admin, {user, "Administrator", "company.com"}}.

{modules,
 [
  {mod_roster,          []},
  {mod_configure,       []},
  {mod_disco,           []},
  {mod_blocking,        []},
  {mod_last,            []},
  {mod_muc,             []},
  {mod_muc_log,         []},
  {mod_offline,         []},
  {mod_privacy,         []},
  {mod_stats,           []}
%%  {mod_vcard_ldap,    []}
]}.


{host_config, "netnumber.com", [{auth_method, ldap},
                                {ldap_encrypt, none},
                                {ldap_servers, ["DC.company.com"]},
%%                              {ldap_uids,    [{"userPrincipalName", "%u"}]},
%%                              {ldap_uids,    [{"userPrincipalName"}]},
%%                              {ldap_uids,    [{"sAMAccountName"}]},
                                {ldap_uids,    [{"sAMAccountName", "%u"}]},
%%                              {ldap_uids,    [{"sAMAccountName",
"%u at company.com"}]},
%%                              {ldap_uidattr,    [{"userPrincipalName"}]},
%%                              {ldap_uidattr,    [{"sAMAccountName"}]},
                                {ldap_base, 
"ou=companyStaff,ou=company,dc=company,dc=com"},
                                {ldap_rootdn, 
"cn=Administrator,cn=Users,dc=company,dc=com"},
                                {ldap_password, "password"}]}.

{listen,
 [
  {5222, ejabberd_c2s, [
        {certfile, "/etc/ejabberd/myserver.pem"}, starttls,
        {access, c2s},
        {shaper, c2s_shaper},
        {max_stanza_size, 65536}
    ]},

  {5280, ejabberd_http, [
                         %%{request_handlers,
                         %% [
                         %%  {["pub", "archive"], mod_http_fileserver}
                         %% ]},
                         captcha,
                         http_bind,
                         http_poll,
                         %%register,
                         web_admin
                        ]}

 ]}.

%% end



ejabberd Log messages:
=INFO REPORT==== 9-Jul-2013::16:10:27 ===
I(<0.265.0>:ejabberd_listener:281) : (#Port<0.4045>) Accepted connection
{{10,0,4,154},40048} -> {{10,0,4,142},5222}

=INFO REPORT==== 9-Jul-2013::16:10:27 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "<?xml
version='1.0' ?>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"3183265919",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "<starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"2531649697",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "<auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'
xmlns:ga='http://www.google.com/talk/protocol/auth'
ga:client-uses-full-bind-result='true'
mechanism='PLAIN'>AGFwbWFydGluZXoAY2hhbmdlbWU=</auth>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.228.0>:eldap:697) : {searchRequest,
                          {'SearchRequest',
                           "ou=companyStaff,ou=company,dc=company,dc=com",
                           wholeSubtree,neverDerefAliases,0,0,false,
                           {equalityMatch,
                            {'AttributeValueAssertion',"sAMAccountName",
                             "apmartinez"}},
                           ["sAMAccountName"]}}


=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.228.0>:eldap:767) : {searchResEntry,
                             {'SearchResultEntry',
                                 "CN=Aaron P. Martinez,OU=Product
Operations,OU=companyStaff,OU=company,DC=company,DC=com",
                                 [{'PartialAttributeList_SEQOF',
                                      "sAMAccountName",
                                      ["apmartinez"]}]}}

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.228.0>:eldap:767) : {searchResDone,
                             {'LDAPResult',success,[],[],asn1_NOVALUE}}

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.236.0>:eldap:697) : {bindRequest,
                             {'BindRequest',3,
                                 "CN=Aaron P. Martinez,OU=Product
Operations,OU=companyStaff,OU=company,DC=company,DC=com",
                                 {simple,"changeme"}}}


=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.236.0>:eldap:767) : {bindResponse,
                             {'BindResponse',success,[],[],asn1_NOVALUE,
                                 asn1_NOVALUE}}

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
I(<0.347.0>:ejabberd_c2s:632) :
({socket_state,tls,{tlssock,#Port<0.4045>,#Port<0.4047>},<0.346.0>})
Accepted authentication for apmartinez by ejabberd_auth_ldap

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"682055299",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "<iq
type='set' id='purple668ff4b3'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><resource>Work</resource></bind></iq>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<iq
id='purple668ff4b3' type='result'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><jid>apmartinez at company.com/Work</jid><
/bind></iq>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "<iq
type='set' id='purple668ff4b4'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></iq>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
I(<0.347.0>:ejabberd_c2s:978) :
({socket_state,tls,{tlssock,#Port<0.4045>,#Port<0.4047>},<0.346.0>})
Forbidden session for apmartinez at company.com/Work

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<iq type='error'
id='purple668ff4b4'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/><error code='405'
type='cancel'><not-allowed
xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>">>

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = "</stream:stream>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.346.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_c2s:1556) : Send XML on stream = "</stream:stream>"

=INFO REPORT==== 9-Jul-2013::16:10:28 ===
D(<0.347.0>:ejabberd_socket:178) : Error in tls:send: {'EXIT',
                                                       {badarg,
                                                        [{erlang,
                                                          port_control,
                                                          [#Port<0.4047>,4,
                                                           "</stream:stream>"],
                                                          []},
                                                         {tls,send,2,
                                                          [{file,"tls.erl"},
                                                           {line,216}]},
                                                         {ejabberd_socket,
                                                          send,2,
                                                          [{file,
                                                           
"ejabberd_socket.erl"},
                                                           {line,171}]},
                                                         {ejabberd_c2s,
                                                          wait_for_session,2,
                                                          [{file,
                                                            "ejabberd_c2s.erl"},
                                                           {line,993}]},
                                                         {p1_fsm,handle_msg,
                                                          10,
                                                          [{file,"p1_fsm.erl"},
                                                           {line,544}]},
                                                         {proc_lib,
                                                          init_p_do_apply,3,
                                                          [{file,
                                                            "proc_lib.erl"},
                                                           {line,227}]}]}}

(ejabberd at workhorse)1> 
(ejabberd at workhorse)1> 
(ejabberd at workhorse)1> 
(ejabberd at workhorse)1> 
=INFO REPORT==== 9-Jul-2013::16:10:51 ===
I(<0.265.0>:ejabberd_listener:281) : (#Port<0.4050>) Accepted connection
{{10,0,4,154},44081} -> {{10,0,4,142},5222}

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "<?xml
version='1.0' ?>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"113086166",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "<starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = []

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"2910813761",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "<auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'
xmlns:ga='http://www.google.com/talk/protocol/auth'
ga:client-uses-full-bind-result='true'
mechanism='PLAIN'>AGFwbWFydGluZXoAY2hhbmdlbWU=</auth>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.228.0>:eldap:697) : {searchRequest,
                          {'SearchRequest',
                           "ou=companyStaff,ou=company,dc=company,dc=com",
                           wholeSubtree,neverDerefAliases,0,0,false,
                           {equalityMatch,
                            {'AttributeValueAssertion',"sAMAccountName",
                             "apmartinez"}},
                           ["sAMAccountName"]}}


=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.228.0>:eldap:767) : {searchResEntry,
                             {'SearchResultEntry',
                                 "CN=Aaron P. Martinez,OU=Product
Operations,OU=companyStaff,OU=company,DC=company,DC=com",
                                 [{'PartialAttributeList_SEQOF',
                                      "sAMAccountName",
                                      ["apmartinez"]}]}}

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.228.0>:eldap:767) : {searchResDone,
                             {'LDAPResult',success,[],[],asn1_NOVALUE}}

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.236.0>:eldap:697) : {bindRequest,
                             {'BindRequest',3,
                                 "CN=Aaron P. Martinez,OU=Product
Operations,OU=companyStaff,OU=company,DC=company,DC=com",
                                 {simple,"changeme"}}}


=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.236.0>:eldap:767) : {bindResponse,
                             {'BindResponse',success,[],[],asn1_NOVALUE,
                                 asn1_NOVALUE}}

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
I(<0.349.0>:ejabberd_c2s:632) :
({socket_state,tls,{tlssock,#Port<0.4050>,#Port<0.4052>},<0.348.0>})
Accepted authentication for apmartinez by ejabberd_auth_ldap

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream =
"<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = [60,63,120,109,108,32,
                                                       118,101,114,115,105,
                                                       111,110,61,39,49,46,48,
                                                       39,63,62,60,115,116,
                                                       114,101,97,109,58,115,
                                                       116,114,101,97,109,32,
                                                       120,109,108,110,115,61,
                                                       39,106,97,98,98,101,
                                                       114,58,99,108,105,101,
                                                       110,116,39,32,120,109,
                                                       108,110,115,58,115,116,
                                                       114,101,97,109,61,39,
                                                       104,116,116,112,58,47,
                                                       47,101,116,104,101,114,
                                                       120,46,106,97,98,98,
                                                       101,114,46,111,114,103,
                                                       47,115,116,114,101,97,
                                                       109,115,39,32,105,100,
                                                       61,39,"784713903",39,
                                                       32,102,114,111,109,61,
                                                       39,"company.com",39,
                                                       [" version='","1.0",
                                                        "'"],
                                                       [" xml:lang='","en",
                                                        "'"],
                                                       62]

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream =
<<"<stream:features><bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></stream:features>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "<iq
type='set' id='purple95a50b0b'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><resource>Work</resource></bind></iq>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<iq
id='purple95a50b0b' type='result'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><jid>apmartinez at company.com/Work</jid><
/bind></iq>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "<iq
type='set' id='purple95a50b0c'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></iq>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
I(<0.349.0>:ejabberd_c2s:978) :
({socket_state,tls,{tlssock,#Port<0.4050>,#Port<0.4052>},<0.348.0>})
Forbidden session for apmartinez at company.com/Work

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = <<"<iq type='error'
id='purple95a50b0c'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/><error code='405'
type='cancel'><not-allowed
xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>">>

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = "</stream:stream>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.349.0>:ejabberd_c2s:1556) : Send XML on stream = "</stream:stream>"

=INFO REPORT==== 9-Jul-2013::16:10:51 ===
D(<0.348.0>:ejabberd_receiver:320) : Received XML on stream = []





Pidgin Debug messages:

(12:25:51) account: Connecting to account apmartinez at company.com/Work.
(12:25:51) connection: Connecting. gc = 0x624d94c0b00
(12:25:51) dnsquery: Performing DNS lookup for 10.0.4.142
(12:25:51) dnsquery: IP resolved for 10.0.4.142
(12:25:51) proxy: Attempting connection to 10.0.4.142
(12:25:51) proxy: Connecting to 10.0.4.142:5222 with no proxy
(12:25:51) proxy: Connection in progress
(12:25:51) proxy: Connecting to 10.0.4.142:5222.
(12:25:51) proxy: Connected to 10.0.4.142:5222.
(12:25:51) jabber: Sending (apmartinez at company.com/Work): <?xml version='1.0' ?>
(12:25:51) jabber: Sending (apmartinez at company.com/Work): <stream:stream
to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(12:25:51) jabber: Recv (171): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
id='1728985692' from='company.com' version='1.0' xml:lang='en'>
(12:25:51) jabber: Recv (180): <stream:features><starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>
(12:25:51) jabber: Sending (apmartinez at company.com/Work): <starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(12:25:51) jabber: Recv (50): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(12:25:51) openssl: Connecting to 10.0.4.142
(12:25:51) openssl: Connecting to 10.0.4.142
(12:25:51) openssl: Connecting to 10.0.4.142
(12:25:51) openssl: Connected to 10.0.4.142
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work):
<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(12:25:51) jabber: Recv (ssl)(171): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
id='2518744403' from='company.com' version='1.0' xml:lang='en'>
(12:25:51) jabber: Recv (ssl)(129): <stream:features><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism></
mechanisms></stream:features>
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work): <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'
xmlns:ga='http://www.google.com/talk/protocol/auth'
ga:client-uses-full-bind-result='true' mechanism='PLAIN'>password removed</auth>
(12:25:51) jabber: Recv (ssl)(51): <success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work):
<stream:stream to='company.com' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(12:25:51) jabber: Recv (ssl)(171): <?xml version='1.0'?><stream:stream
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
id='1661639817' from='company.com' version='1.0' xml:lang='en'>
(12:25:51) jabber: Recv (ssl)(137): <stream:features><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'/><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></stream:features>
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work): <iq
type='set' id='purple3983f7bd'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><resource>Work</resource></bind></iq>
(12:25:51) jabber: Recv (ssl)(137): <iq id='purple3983f7bd'
type='result'><bind
xmlns='urn:ietf:params:xml:ns:xmpp-bind'><jid>apmartinez at company.com/Work</jid><
/bind></iq>
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work): <iq
type='set' id='purple3983f7be'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/></iq>
(12:25:51) jabber: Recv (ssl)(194): <iq type='error'
id='purple3983f7be'><session
xmlns='urn:ietf:params:xml:ns:xmpp-session'/><error code='405'
type='cancel'><not-allowed
xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
(12:25:51) connection: Connection error on 0x624d94c0b00 (reason: 0
description: Error initializing session)
(12:25:51) account: Disconnecting account apmartinez at company.com/Work
(0x624aaf2f000)
(12:25:51) connection: Disconnecting connection 0x624d94c0b00
(12:25:51) jabber: Sending (ssl) (apmartinez at company.com/Work): </stream:stream>
(12:25:51) connection: Destroying connection 0x624d94c0b00








More information about the ejabberd mailing list