After hours of trying to find any suitable solution, i have to bug you guys with my stupid problem.
A friend runs his own ejabberd server and now i do the same. We both can communicate with people using public servers like jabber.org, but when we try to add each other, no communication is possible. We even can't authorize each other and when we try to write a message to the other, always the error code 404 appears and we don't know how to solve this after hours of trying.
Which configuration is still missing, what can we do?
Adding the line
{acl, mydomain, {server, "mydomain.com"}}.
didn't help
Opening the Port 5269 in and outgoing didn't help.
Can you guys help us?
THANKS!!!!!!!!!!!!!
Check log files in both servers
First: adding that line doesn't have any special meaning. Obviously it didn't help :)
Second: it's strange that both servers can connect to other same servers, but both servers can't connect between them. It may be a problem in your server, in his server, or in both servers.
Check the files ejabberd.log and sasl.log of your server and his server when you try to contact the other server for the first time in several minutes (10 minutes for example).
I tried in my test server, and obviously it was predestinated to fail. Look at the timestamps:
=INFO REPORT==== 30-Sep-2008::19:35:41 === I(<0.532.0>:ejabberd_s2s:367) : New s2s connection started <0.554.0> =INFO REPORT==== 30-Sep-2008::19:35:41 === I(<0.554.0>:ejabberd_s2s_out:1015) : Trying to open s2s connection: localhost -> example.org =PROGRESS REPORT==== 30-Sep-2008::19:35:47 === supervisor: {local,inet_gethost_native_sup} started: [{pid,<0.556.0>},{mfa,{inet_gethost_native,init,[[]]}}] =PROGRESS REPORT==== 30-Sep-2008::19:35:47 === supervisor: {local,kernel_safe_sup} started: [{pid,<0.555.0>}, {name,inet_gethost_native_sup}, {mfa,{inet_gethost_native,start_link,[]}}, {restart_type,temporary}, {shutdown,1000}, {child_type,worker}] =INFO REPORT==== 30-Sep-2008::19:39:10 === I(<0.554.0>:ejabberd_s2s_out:224) : s2s connection: localhost -> example.org (remote server not found) =INFO REPORT==== 30-Sep-2008::19:39:25 === I(<0.554.0>:ejabberd_s2s_out:782) : Reconnect delay expired: Will now retry to connect to example.org when needed.Third: I deleted your duplicated thread "Communication to public Servers work, Communication between ejabberd & ejabberd doesn't - WHY???"
thank you badlop for trying
thank you badlop for trying to help us. the 'problem' in your config file seems clear, unfortunately the situation in my logs isn't that clear. here we go, i really hope that you or someone else can help us.
+++ ejabberd.log +++
=INFO REPORT==== 2008-09-30 20:17:32 ===I(<0.994.0>:ejabberd_listener:90): (#Port<0.1156>) Accepted connection {{90,231,16,12},34752} -> {{90,231,16,12},5223}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1060.0>:ejabberd_c2s:450): ({tlssock,#Port<0.1156>,#Port<0.1194>}) Accepted authentication for williwonka
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1060.0>:ejabberd_c2s:631): ({tlssock,#Port<0.1156>,#Port<0.1194>}) Opened session for
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1062.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"users.jabber.org",
{new,"2927001837"}}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1063.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"jabber.org",
{new,"1561614840"}}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1064.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"gmx.net",
{new,"3930858788"}}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.995.0>:ejabberd_listener:90): (#Port<0.1158>) Accepted connection {{90,231,16,12},52183} -> {{90,231,16,12},5269}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1068.0>:ejabberd_s2s_in:102): started: {gen_tcp,#Port<0.1158>}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1068.0>:ejabberd_s2s_in:308): GET KEY: {"wonkasdomain.com",
"gmx.net",
[],
"3584344914"}
=INFO REPORT==== 2008-09-30 20:17:32 ===
I(<0.1070.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"gmx.net",
{verify,
<0.1068.0>,
"3584344914",
"3540096283"}}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1070.0>:ejabberd_s2s_out:246): recv verify: {"gmx.net",
"wonkasdomain.com",
"3540096283",
"valid"}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1068.0>:ejabberd_s2s_in:328): VERIFY KEY: {"wonkasdomain.com",
"gmx.net",
"2787028728",
"3930858788"}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1064.0>:ejabberd_s2s_out:235): recv result: {"gmx.net",
"wonkasdomain.com",
[],
"valid"}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1062.0>:ejabberd_s2s_out:632): terminated: normal
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.995.0>:ejabberd_listener:90): (#Port<0.1220>) Accepted connection {{208,68,163,220},52606} -> {{90,231,16,12},5269}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1074.0>:ejabberd_s2s_in:102): started: {gen_tcp,#Port<0.1220>}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1074.0>:ejabberd_s2s_in:308): GET KEY: {"wonkasdomain.com",
"jabber.org",
[],
"3168819544"}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1076.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"jabber.org",
{verify,
<0.1074.0>,
"3168819544",
"448678120"}}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1074.0>:ejabberd_s2s_in:328): VERIFY KEY: {"wonkasdomain.com",
"jabber.org",
"2900826997",
"1561614840"}
=INFO REPORT==== 2008-09-30 20:17:33 ===
I(<0.1063.0>:ejabberd_s2s_out:235): recv result: {"jabber.org",
"wonkasdomain.com",
[],
"valid"}
=INFO REPORT==== 2008-09-30 20:17:34 ===
I(<0.1076.0>:ejabberd_s2s_out:246): recv verify: {"jabber.org",
"wonkasdomain.com",
"448678120",
"valid"}
=INFO REPORT==== 2008-09-30 20:17:51 ===
I(<0.1078.0>:ejabberd_s2s_out:104): started: {"wonkasdomain.com",
"wonkas-friend-domain.com",
{new,"444652800"}}
=INFO REPORT==== 2008-09-30 20:17:52 ===
I(<0.994.0>:ejabberd_listener:90): (#Port<0.1196>) Accepted connection {{194,95,9,11},56869} -> {{90,231,16,12},5223}
++++++++++++++++++++++ THIS WAS ejabberd.log +++++++++++++++++++++++++
++++ This is sasl.log ++++
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,sasl_safe_sup}
started: [{pid,<0.807.0>},
{name,alarm_handler},
{mfa,{alarm_handler,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,sasl_safe_sup}
started: [{pid,<0.808.0>},
{name,overload},
{mfa,{overload,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,sasl_sup}
started: [{pid,<0.806.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==== 30-Sep-2008::20:17:30 ===
supervisor: {local,sasl_sup}
started: [{pid,<0.809.0>},
{name,release_handler},
{mfa,{release_handler,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
application: sasl
started_at: 'ejabberd@wonkasserver'
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.813.0>},
{name,dets_sup},
{mfa,{dets_sup,start_link,[]}},
{restart_type,permanent},
{shutdown,1000},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.814.0>},
{name,dets},
{mfa,{dets_server,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_sup}
started: [{pid,<0.822.0>},
{name,mnesia_event},
{mfa,{mnesia_sup,start_event,[]}},
{restart_type,permanent},
{shutdown,30000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.824.0>},
{name,mnesia_monitor},
{mfa,{mnesia_monitor,start,[]}},
{restart_type,permanent},
{shutdown,3000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.825.0>},
{name,mnesia_subscr},
{mfa,{mnesia_subscr,start,[]}},
{restart_type,permanent},
{shutdown,3000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.826.0>},
{name,mnesia_locker},
{mfa,{mnesia_locker,start,[]}},
{restart_type,permanent},
{shutdown,3000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.827.0>},
{name,mnesia_recover},
{mfa,{mnesia_recover,start,[]}},
{restart_type,permanent},
{shutdown,180000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.832.0>},
{name,disk_log_sup},
{mfa,{disk_log_sup,start_link,[]}},
{restart_type,permanent},
{shutdown,1000},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.833.0>},
{name,disk_log_server},
{mfa,{disk_log_server,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.834.0>},
{name,pg2},
{mfa,{pg2,start_link,[]}},
{restart_type,permanent},
{shutdown,1000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.847.0>},
{name,timer_server},
{mfa,{timer,start_link,[]}},
{restart_type,permanent},
{shutdown,1000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.828.0>},
{name,mnesia_tm},
{mfa,{mnesia_tm,start,[]}},
{restart_type,permanent},
{shutdown,30000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.848.0>},
{name,mnesia_checkpoint_sup},
{mfa,{mnesia_checkpoint_sup,start,[]}},
{restart_type,permanent},
{shutdown,infinity},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.849.0>},
{name,mnesia_snmp_sup},
{mfa,{mnesia_snmp_sup,start,[]}},
{restart_type,permanent},
{shutdown,infinity},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.850.0>},
{name,mnesia_controller},
{mfa,{mnesia_controller,start,[]}},
{restart_type,permanent},
{shutdown,3000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_kernel_sup}
started: [{pid,<0.851.0>},
{name,mnesia_late_loader},
{mfa,{mnesia_late_loader,start,[]}},
{restart_type,permanent},
{shutdown,3000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,mnesia_sup}
started: [{pid,<0.823.0>},
{name,mnesia_kernel_sup},
{mfa,{mnesia_kernel_sup,start,[]}},
{restart_type,permanent},
{shutdown,infinity},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
application: mnesia
started_at: 'ejabberd@wonkasserver'
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,crypto_sup}
started: [{pid,<0.943.0>},
{name,crypto_server},
{mfa,{crypto_server,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
application: crypto
started_at: 'ejabberd@wonkasserver'
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,ssl_sup}
started: [{pid,<0.950.0>},
{name,ssl_server},
{mfa,{ssl_server,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
supervisor: {local,ssl_sup}
started: [{pid,<0.951.0>},
{name,ssl_broker_sup},
{mfa,{ssl_broker_sup,start_link,[]}},
{restart_type,permanent},
{shutdown,2000},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:30 ===
application: ssl
started_at: 'ejabberd@wonkasserver'
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.970.0>},
{name,ejabberd_hooks},
{mfa,{ejabberd_hooks,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.971.0>},
{name,stringprep},
{mfa,{stringprep,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.974.0>},
{name,ejabberd_router},
{mfa,{ejabberd_router,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.975.0>},
{name,ejabberd_sm},
{mfa,{ejabberd_sm,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.981.0>},
{name,ejabberd_s2s},
{mfa,{ejabberd_s2s,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.984.0>},
{name,ejabberd_local},
{mfa,{ejabberd_local,start_link,[]}},
{restart_type,permanent},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.985.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.986.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.987.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.988.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.989.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.990.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.991.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_listeners}
started: [{pid,<0.993.0>},
{name,5222},
{mfa,{ejabberd_listener,
start,
[5222,
ejabberd_c2s,
[{access,c2s},
starttls,
{certfile,"/etc/ssl/certs/ejabberd.pem"},
{shaper,c2s_shaper}]]}},
{restart_type,transient},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_listeners}
started: [{pid,<0.994.0>},
{name,5223},
{mfa,{ejabberd_listener,
start,
[5223,
ejabberd_c2s,
[{access,c2s},
tls,
{certfile,"/etc/ssl/certs/ejabberd.pem"},
{shaper,c2s_shaper}]]}},
{restart_type,transient},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_listeners}
started: [{pid,<0.995.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==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_listeners}
started: [{pid,<0.996.0>},
{name,5280},
{mfa,{ejabberd_listener,
start,
[5280,ejabberd_http,[http_poll,web_admin]]}},
{restart_type,transient},
{shutdown,brutal_kill},
{child_type,worker}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
supervisor: {local,ejabberd_sup}
started: [{pid,<0.992.0>},
{name,ejabberd_listener},
{mfa,{ejabberd_listener,start_link,[]}},
{restart_type,permanent},
{shutdown,infinity},
{child_type,supervisor}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:31 ===
application: ejabberd
started_at: 'ejabberd@wonkasserver'
=PROGRESS REPORT==== 30-Sep-2008::20:17:32 ===
supervisor: {local,inet_gethost_native_sup}
started: [{pid,<0.1066.0>},{mfa,{inet_gethost_native,init,[[]]}}]
=PROGRESS REPORT==== 30-Sep-2008::20:17:32 ===
supervisor: {local,kernel_safe_sup}
started: [{pid,<0.1065.0>},
{name,inet_gethost_native_sup},
{mfa,{inet_gethost_native,start_link,[]}},
{restart_type,temporary},
{shutdown,1000},
{child_type,worker}]
+++++++++++++++ END OF sasl.log +++++++++++++++++++
if there's a hint in it, that it depends on the firewall setting, even with deactivated firewall there is no possibility to get this problem solved... not yet :)
THE ERROR I RECEIVE WHEN SENDING MESSAGES TO MY FRIENDS SERVER, NO MATTER WHICH JABBER ID, IS THE FOLLOWING: Message delivery to failed: (Code 404)