`remote-server-timeout` with pre authenticated in-band registration (#604)

PositivDenken opened 3 days ago

Hey,

when trying to register a new account through an invite the form of

xmpp:dev.jwchat.org?register;preauth=lJphv0s2LzqlTH37XFU0olgZ

I first see a question to register a new account and then an error saying remote-server-timeout that pops up immediately.

Here's the log from my server (ejabberd from master with patches for said pre-authenticated in-band registration.

2025-11-13 13:13:18.001857+01:00 [info] (<0.687.0>) Accepted connection [::ffff:83.251.228.150]:50734 -> [::ffff:37.27.222.134]:15222
2025-11-13 13:13:18.002480+01:00 [notice] (tcp|<0.687.0>) Received XML on stream = <<"<stream:stream to='dev.jwchat.org'  version='1.0' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>">>
2025-11-13 13:13:18.003129+01:00 [notice] (tcp|<0.687.0>) Send XML on stream = <<"<?xml version='1.0'?><stream:stream id='16845107284923306068' version='1.0' xml:lang='en' xmlns:stream='http://etherx.jabber.org/streams' from='dev.jwchat.org' xmlns='jabber:client'>">>
2025-11-13 13:13:18.003684+01:00 [debug] Running hook c2s_stream_started: mod_client_state:c2s_stream_started/2
2025-11-13 13:13:18.003860+01:00 [debug] Running hook c2s_stream_started: mod_stream_mgmt:c2s_stream_started/2
2025-11-13 13:13:18.004003+01:00 [debug] Running hook c2s_stream_started: mod_fail2ban:c2s_stream_started/2
2025-11-13 13:13:18.004118+01:00 [notice] (tcp|<0.687.0>) Send XML on stream = <<"<stream:features><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'><required/></starttls></stream:features>">>
2025-11-13 13:13:18.004374+01:00 [debug] Running hook c2s_handle_send: mod_push:c2s_stanza/3
2025-11-13 13:13:18.004477+01:00 [debug] Running hook c2s_handle_send: mod_push_keepalive:c2s_stanza/3
2025-11-13 13:13:18.004573+01:00 [debug] Running hook c2s_handle_send: mod_stream_mgmt:c2s_handle_send/3
2025-11-13 13:13:18.072897+01:00 [notice] (tcp|<0.687.0>) Received XML on stream = <<"<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>">>
2025-11-13 13:13:18.073295+01:00 [debug] Running hook c2s_handle_recv: mod_stream_mgmt:c2s_handle_recv/3
2025-11-13 13:13:18.075298+01:00 [notice] (tcp|<0.687.0>) Send XML on stream = <<"<proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>">>
2025-11-13 13:13:18.075628+01:00 [debug] Running hook c2s_handle_send: mod_push:c2s_stanza/3
2025-11-13 13:13:18.075744+01:00 [debug] Running hook c2s_handle_send: mod_push_keepalive:c2s_stanza/3
2025-11-13 13:13:18.075852+01:00 [debug] Running hook c2s_handle_send: mod_stream_mgmt:c2s_handle_send/3
2025-11-13 13:13:18.075982+01:00 [warning] unexpected event from XML driver: {xmlstreamelement,
                                   {xmlel,<<"starttls">>,
                                    [{<<"xmlns">>,
                                      <<"urn:ietf:params:xml:ns:xmpp-tls">>}],
                                    []}}; xmlstreamstart was expected

It looks like the client gets stuck at sending the starttls sequence rather than actually starting a new stream after tls negotiation.

Activities

Type	Bug
Priority	Normal
Assignee	Andrzej Wójcik (Tigase)
Version	latest from app store
Sprints	n/a
Customer	n/a

Issue Votes (0)

Watchers (4)

Reference

tigase/_clients/siskin-im#604