[ejabberd] LDAP random access and unable to publish vcard

Fernando de los Ríos Sánchez fdelosrios at ub.edu
Mon Mar 14 14:24:55 MSK 2005


Those are the logs. They were created just starting ejabberd and then 4 
users trying to log in. 2 of them had success but one got stuck when it 
tried to publish the vcard. The other 2 didn't. Thanks a lot.

--ejabberd.log--

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.249.0>:ejabberd_listener:89): (#Port<0.310>) Accepted connection 
{{161,116,48,55},1140} -> {{161,116,48,11},5222}

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.249.0>:ejabberd_listener:89): (#Port<0.348>) Accepted connection 
{{161,116,48,55},1141} -> {{161,116,48,11},5222}

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.249.0>:ejabberd_listener:89): (#Port<0.350>) Accepted connection 
{{161,116,48,55},1142} -> {{161,116,48,11},5222}

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.249.0>:ejabberd_listener:89): (#Port<0.352>) Accepted connection 
{{161,116,48,55},1143} -> {{161,116,48,11},5222}

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.312.0>:ejabberd_c2s:295): (#Port<0.348>) Accepted legacy 
authentification for temp at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.315.0>:ejabberd_c2s:295): (#Port<0.350>) Accepted legacy 
authentification for fernando at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.318.0>:ejabberd_c2s:295): (#Port<0.352>) Accepted legacy 
authentification for montse at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:41:32 ===
I(<0.309.0>:ejabberd_c2s:295): (#Port<0.310>) Accepted legacy 
authentification for maribel at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:44:58 ===
I(<0.318.0>:ejabberd_c2s:931): (#Port<0.352>) Close session for 
montse at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:44:58 ===
I(<0.309.0>:ejabberd_c2s:931): (#Port<0.310>) Close session for 
maribel at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:44:58 ===
I(<0.315.0>:ejabberd_c2s:931): (#Port<0.350>) Close session for 
fernando at nasum.ere.ub.es/Psi

=INFO REPORT==== 2005-03-14 11:44:58 ===
I(<0.312.0>:ejabberd_c2s:931): (#Port<0.348>) Close session for 
temp at nasum.ere.ub.es/Psi
--sasl.log--

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,sasl_safe_sup}
              started: [{pid,<0.53.0>},
                        {name,alarm_handler},
                        {mfa,{alarm_handler,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,sasl_safe_sup}
              started: [{pid,<0.54.0>},
                        {name,overload},
                        {mfa,{overload,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,sasl_sup}
              started: [{pid,<0.52.0>},
                        {name,sasl_safe_sup},
                        {mfa,{supervisor,
                                 start_link,
                                 [{local,sasl_safe_sup},sasl,safe]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,sasl_sup}
              started: [{pid,<0.55.0>},
                        {name,release_handler},
                        {mfa,{release_handler,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
          application: sasl
           started_at: 'ejabberd at nasum.ere.ub.es'

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.78.0>},
                        {name,dets_sup},
                        {mfa,{dets_sup,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,1000},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.79.0>},
                        {name,dets},
                        {mfa,{dets_server,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_sup}
              started: [{pid,<0.85.0>},
                        {name,mnesia_event},
                        {mfa,{mnesia_sup,start_event,[]}},
                        {restart_type,permanent},
                        {shutdown,30000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.87.0>},
                        {name,mnesia_monitor},
                        {mfa,{mnesia_monitor,start,[]}},
                        {restart_type,permanent},
                        {shutdown,3000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.88.0>},
                        {name,mnesia_subscr},
                        {mfa,{mnesia_subscr,start,[]}},
                        {restart_type,permanent},
                        {shutdown,3000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.89.0>},
                        {name,mnesia_locker},
                        {mfa,{mnesia_locker,start,[]}},
                        {restart_type,permanent},
                        {shutdown,3000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.90.0>},
                        {name,mnesia_recover},
                        {mfa,{mnesia_recover,start,[]}},
                        {restart_type,permanent},
                        {shutdown,180000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.93.0>},
                        {name,disk_log_sup},
                        {mfa,{disk_log_sup,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,1000},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.94.0>},
                        {name,disk_log_server},
                        {mfa,{disk_log_server,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.95.0>},
                        {name,pg2},
                        {mfa,{pg2,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,1000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,kernel_safe_sup}
              started: [{pid,<0.102.0>},
                        {name,timer_server},
                        {mfa,{timer,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,1000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.91.0>},
                        {name,mnesia_tm},
                        {mfa,{mnesia_tm,start,[]}},
                        {restart_type,permanent},
                        {shutdown,30000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.103.0>},
                        {name,mnesia_checkpoint_sup},
                        {mfa,{mnesia_checkpoint_sup,start,[]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.104.0>},
                        {name,mnesia_snmp_sup},
                        {mfa,{mnesia_snmp_sup,start,[]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.105.0>},
                        {name,mnesia_controller},
                        {mfa,{mnesia_controller,start,[]}},
                        {restart_type,permanent},
                        {shutdown,3000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_kernel_sup}
              started: [{pid,<0.106.0>},
                        {name,mnesia_late_loader},
                        {mfa,{mnesia_late_loader,start,[]}},
                        {restart_type,permanent},
                        {shutdown,3000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,mnesia_sup}
              started: [{pid,<0.86.0>},
                        {name,mnesia_kernel_sup},
                        {mfa,{mnesia_kernel_sup,start,[]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
          application: mnesia
           started_at: 'ejabberd at nasum.ere.ub.es'

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,crypto_sup}
              started: [{pid,<0.192.0>},
                        {name,crypto_server},
                        {mfa,{crypto_server,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
          application: crypto
           started_at: 'ejabberd at nasum.ere.ub.es'

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ssl_sup}
              started: [{pid,<0.207.0>},
                        {name,ssl_server},
                        {mfa,{ssl_server,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ssl_sup}
              started: [{pid,<0.208.0>},
                        {name,ssl_broker_sup},
                        {mfa,{ssl_broker_sup,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,2000},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
          application: ssl
           started_at: 'ejabberd at nasum.ere.ub.es'

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.226.0>},
                        {name,ejabberd_hooks},
                        {mfa,{ejabberd_hooks,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.227.0>},
                        {name,ejabberd_router},
                        {mfa,{ejabberd_router,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.229.0>},
                        {name,ejabberd_sm},
                        {mfa,{ejabberd_sm,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.232.0>},
                        {name,ejabberd_s2s},
                        {mfa,{ejabberd_s2s,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.235.0>},
                        {name,ejabberd_local},
                        {mfa,{ejabberd_local,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.237.0>},
                        {name,stringprep},
                        {mfa,{stringprep,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.240.0>},
                        {name,ejabberd_c2s_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_c2s_sup,ejabberd_c2s]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.242.0>},
                        {name,ejabberd_s2s_in_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_s2s_in_sup,ejabberd_s2s_in]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.243.0>},
                        {name,ejabberd_s2s_out_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_s2s_out_sup,ejabberd_s2s_out]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.244.0>},
                        {name,ejabberd_service_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_service_sup,ejabberd_service]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.245.0>},
                        {name,ejabberd_http_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_http_sup,ejabberd_http]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.246.0>},
                        {name,ejabberd_http_poll_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
 
[ejabberd_http_poll_sup,ejabberd_http_poll]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.247.0>},
                        {name,ejabberd_iq_sup},
                        {mfa,{ejabberd_tmp_sup,
                                 start_link,
                                 [ejabberd_iq_sup,gen_iq_handler]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_listeners}
              started: [{pid,<0.249.0>},
                        {name,5222},
                        {mfa,{ejabberd_listener,
                                 start,
                                 [5222,
                                  ejabberd_c2s,
                                  [{access,c2s},
                                   {shaper,c2s_shaper},
                                   starttls,
 
{certfile,"/etc/ejabberd/jabber.pem"}]]}},
                        {restart_type,transient},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_listeners}
              started: [{pid,<0.250.0>},
                        {name,5223},
                        {mfa,{ejabberd_listener,
                                 start,
                                 [5223,
                                  ejabberd_c2s,
                                  [{access,c2s},
                                   tls,
 
{certfile,"/etc/ejabberd/jabber.pem"}]]}},
                        {restart_type,transient},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_listeners}
              started: [{pid,<0.251.0>},
                        {name,5269},
                        {mfa,{ejabberd_listener,
                                 start,
 
[5269,ejabberd_s2s_in,[{shaper,s2s_shaper}]]}},
                        {restart_type,transient},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_listeners}
              started: [{pid,<0.252.0>},
                        {name,5280},
                        {mfa,{ejabberd_listener,
                                 start,
                                 [5280,ejabberd_http,[web_admin]]}},
                        {restart_type,transient},
                        {shutdown,brutal_kill},
                        {child_type,worker}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:26 ===
           supervisor: {local,ejabberd_sup}
              started: [{pid,<0.248.0>},
                        {name,ejabberd_listener},
                        {mfa,{ejabberd_listener,start_link,[]}},
                        {restart_type,permanent},
                        {shutdown,infinity},
                        {child_type,supervisor}]

=PROGRESS REPORT==== 14-Mar-2005::11:46:27 ===
          application: ejabberd
           started_at: 'ejabberd at nasum.ere.ub.es'



Alexey Shchepin escribió:
> Hello, Fernando!
> 
> On Fri, 11 Mar 2005 12:47:42 +0100, you said:
> 
>  FdlRS> Hi all, I "successfully" compiled and installed ejabberd but something
>  FdlRS> very strange happens to me now. For a while all was running apparently
>  FdlRS> right but now ejabberd behaves in a way I cannot understand. I try to
>  FdlRS> connect with the Psi client, with 4 accounts at once that exists in my
>  FdlRS> LDAP server but they connect randomly, I'm serious. Sometimes 2
>  FdlRS> connect, sometimes 1 or all them. The ones that cannot connect keep
>  FdlRS> trying until I turn off the client. The ones that do sometimes can
>  FdlRS> publish the vcard correctly, some others keep trying but they can't,
>  FdlRS> some others crash with a blank reason, etc. I happens through port 5222
>  FdlRS> in plain text and through port 5223 in plain text over SSL. I paste
>  FdlRS> here a tcp session dump, my ejabberd.cfg and my ejabberd run
>  FdlRS> script. Bye the way, I run it under ejabberd account, I already checked
>  FdlRS> the file permissions and it does the LDAP queries and they seem
>  FdlRS> correct.  Thanks in advance and sorry for my english :s.
> 
> What you see in ejabberd.log and ejabberd.sasl.log when clients connect?
> 
> _______________________________________________
> ejabberd mailing list
> ejabberd at jabber.ru
> http://lists.jabber.ru/mailman/listinfo/ejabberd



More information about the ejabberd mailing list