#1534 No stream features to offer

Reporter Thomas
Owner Nobody
Created
Updated
Stars ★ (1)
Tags
  • Type-Defect
  • Priority-Medium
  • Status-Invalid
  1. Thomas on

    What steps will reproduce the problem? Updated to 0.11.5-1~buster6 CLients cannot connect anymore, neither with mysql storage, nor internal. Debian Buster Logs: Apr 19 12:39:22 c2s559dbfb139f0 debug Sending[c2s_unauthed]: <stream:error> Apr 19 12:39:22 c2s559dbfb139f0 debug Sending[c2s_unauthed]: </stream:stream> Apr 19 12:39:22 c2s559dbfb139f0 debug c2s stream for 5.147.131.148 closed: No stream features to proceed with Apr 19 12:39:22 c2s559dbfb139f0 debug Destroying session for (unknown) ((unknown)@okaris.de): No stream features to proceed with Apr 19 12:39:22 c2s559dbfb139f0 info Client disconnected: connection closed Apr 19 12:39:22 c2s559dbfb139f0 debug Destroying session for (unknown) ((unknown)@(unknown)) Apr 19 12:39:22 socket debug server.lua: closed client handler and removed socket from list Apr 19 12:39:25 socket debug server.lua: accepted new client connection from 5.147.131.148:48418 to 5222 Apr 19 12:39:25 c2s559dbfb17610 info Client connected Apr 19 12:39:25 c2s559dbfb17610 debug Client sent opening <stream:stream> to okaris.de Apr 19 12:39:25 c2s559dbfb17610 debug Sending[c2s_unauthed]: <?xml version='1.0'?> Apr 19 12:39:25 c2s559dbfb17610 debug Sending[c2s_unauthed]: <stream:stream from='okaris.de' xmlns='jabber:client' version='1.0' xmlns:stream='http://etherx.jabber.org/streams' xml:lang='en' id='9ced4b07-1fd8-4615-b49a-517f64ce6505'> Apr 19 12:39:25 c2s559dbfb17610 debug Sent reply <stream:stream> to client Apr 19 12:39:25 c2s559dbfb17610 warn No stream features to offer Apr 19 12:39:25 c2s559dbfb17610 debug Disconnecting client, <stream:error> is: <stream:error><undefined-condition xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text xmlns='urn:ietf:params:xml:ns:xmpp-streams'>No stream features to proceed with</text></stream:error> Apr 19 12:39:25 c2s559dbfb17610 debug Sending[c2s_unauthed]: <stream:error> Apr 19 12:39:25 c2s559dbfb17610 debug Sending[c2s_unauthed]: </stream:stream> Apr 19 12:39:25 c2s559dbfb17610 debug c2s stream for 5.147.131.148 closed: No stream features to proceed with Apr 19 12:39:25 c2s559dbfb17610 debug Destroying session for (unknown) ((unknown)@okaris.de): No stream features to proceed with Apr 19 12:39:25 c2s559dbfb17610 info Client disconnected: connection closed Apr 19 12:39:25 c2s559dbfb17610 debug Destroying session for (unknown) ((unknown)@(unknown)) Apr 19 12:39:25 socket debug server.lua: closed client handler and removed socket from list

  2. Zash on

    Usually this means either TLS or authentication is misconfigured. In 0.12 this message has changed to either No stream features to offer on insecure session. Check encryption and security settings. or No stream features to offer on secure session. Check authentication settings. Hope this helps.

  3. Thomas on

    How can I debug this? I see no other cert logs...

  4. Thomas on

    I see those: Apr 19 14:04:23 certmanager debug Searching /etc/prosody/certs/ for a key and certificate for client_https... Apr 19 14:04:23 certmanager debug No certificate/key found for client_https Apr 19 14:04:23 certmanager debug Searching /etc/prosody/certs/ for a key and certificate for legacy_ssl... Apr 19 14:04:23 certmanager debug No certificate/key found for legacy_ssl Apr 19 14:04:23 certmanager debug Searching /etc/prosody/certs/ for a key and certificate for legacy_ssl... Apr 19 14:04:23 certmanager debug No certificate/key found for legacy_ssl

  5. Thomas on

    1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 Apr 19 14:43:20 startup info Hello and welcome to Prosody version 0.11.5 Apr 19 14:43:20 stats debug Statistics disabled Apr 19 14:43:20 certmanager debug Searching /etc/prosody/certs/ for a key and certificate for client_https... Apr 19 14:43:20 certmanager debug No certificate/key found for client_https Apr 19 14:43:20 startup info Prosody is using the select backend for connection handling Apr 19 14:43:20 hostmanager debug Activated host: conference.okaris.de Apr 19 14:43:20 conference.okaris.de:storage_sql debug Creating new engine Apr 19 14:43:20 conference.okaris.de:mam_muc warn mod_mam_muc is compatible with Prosody up to 0.10.x, use mod_muc_mam with later versions Apr 19 14:43:20 conference.okaris.de:muc_mam debug muc_log_expires_after = 604800 -- in seconds Apr 19 14:43:20 portmanager debug No active service for s2s, activating... Apr 19 14:43:20 socket debug server.lua: new server listener on '[::]:5269' Apr 19 14:43:20 portmanager debug Added listening service s2s to [::]:5269 Apr 19 14:43:20 socket debug server.lua: new server listener on '[*]:5269' Apr 19 14:43:20 portmanager debug Added listening service s2s to [*]:5269 Apr 19 14:43:20 portmanager info Activated service 's2s' on [::]:5269, [*]:5269 Apr 19 14:43:20 hostmanager debug Activated host: proxy.okaris.de Apr 19 14:43:20 portmanager debug No active service for proxy65, activating... Apr 19 14:43:20 socket debug server.lua: new server listener on '[::]:5000' Apr 19 14:43:20 portmanager debug Added listening service proxy65 to [::]:5000 Apr 19 14:43:20 socket debug server.lua: new server listener on '[*]:5000' Apr 19 14:43:20 portmanager debug Added listening service proxy65 to [*]:5000 Apr 19 14:43:20 portmanager info Activated service 'proxy65' on [::]:5000, [*]:5000 Apr 19 14:43:20 hostmanager debug Activated host: okaris.de Apr 19 14:43:20 usermanager debug Host 'okaris.de' now set to use user provider 'internal_hashed' Apr 19 14:43:20 portmanager debug No active service for c2s, activating... Apr 19 14:43:20 socket debug server.lua: new server listener on '[::]:5222' Apr 19 14:43:20 portmanager debug Added listening service c2s to [::]:5222 Apr 19 14:43:20 socket debug server.lua: new server listener on '[*]:5222' Apr 19 14:43:20 portmanager debug Added listening service c2s to [*]:5222 Apr 19 14:43:20 portmanager info Activated service 'c2s' on [::]:5222, [*]:5222 Apr 19 14:43:20 portmanager debug No active service for legacy_ssl, activating... Apr 19 14:43:20 portmanager info Activated service 'legacy_ssl' on no ports Apr 19 14:43:21 runnerS3LcavKQ debug creating new coroutine Apr 19 14:43:21 sql debug Connecting to [MySQL] prosody... Apr 19 14:43:21 sql debug SQL transaction begin [function(sql.lua:315)] Apr 19 14:43:21 sql debug SQL transaction success [function(sql.lua:315)] Apr 19 14:43:21 sql debug Using encoding 'utf8mb4' for database connection Apr 19 14:43:21 sql debug SQL transaction begin [function(sql.lua:329)] Apr 19 14:43:21 sql debug SQL transaction success [function(sql.lua:329)] Apr 19 14:43:21 sql debug SQL transaction begin [function(sql.lua:335)] Apr 19 14:43:21 sql debug SQL transaction success [function(sql.lua:335)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:485)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:485)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:504)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:504)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:512)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:512)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:557)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:557)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:576)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:576)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:137)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:137)] Apr 19 14:43:21 conference.okaris.de:muc_mam debug Messages from "2020-04-12" should be expired Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:62)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:62)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:378)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:378)] Apr 19 14:43:21 sql debug SQL transaction begin [function(mod_storage_sql.lua:378)] Apr 19 14:43:21 sql debug SQL transaction success [function(mod_storage_sql.lua:378)] Apr 19 14:43:21 conference.okaris.de:muc_mam info Deleted 0 expired messages for 2 rooms Apr 19 14:43:24 socket debug server.lua: accepted new client connection from 94.130.132.243:53813 to 5269 Apr 19 14:43:24 s2sin55681a7abc10 debug Incoming s2s connection Apr 19 14:43:24 runnernLei-Ljg debug creating new coroutine Apr 19 14:43:24 s2sin55681a7abc10 debug Incoming s2s received <stream:stream from='quicksy.im' xml:lang='en' to='okaris.de' version='1.0' xmlns='http://etherx.jabber.org/streams'> Apr 19 14:43:24 s2sin55681a7abc10 debug Sending[s2sin_unauthed]: <?xml version='1.0'?> Apr 19 14:43:24 s2sin55681a7abc10 debug Sending[s2sin_unauthed]: <stream:stream xmlns='jabber:server' xml:lang='en' id='53ad3e90-ff6c-4835-a3c0-71b085607b97' to='quicksy.im' xmlns:stream='http://etherx.jabber.org/streams' from='okaris.de' version='1.0'> Apr 19 14:43:24 s2sin55681a7abc10 warn No stream features to offer, giving up Apr 19 14:43:24 s2sin55681a7abc10 debug Disconnecting 94.130.132.243[s2sin_unauthed], <stream:error> is: <stream:error><undefined-condition xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text xmlns='urn:ietf:params:xml:ns:xmpp-streams'>No stream features to offer</text></stream:error> Apr 19 14:43:24 s2sin55681a7abc10 debug Sending[s2sin_unauthed]: <stream:error> Apr 19 14:43:24 s2sin55681a7abc10 debug Sending[s2sin_unauthed]: </stream:stream> Apr 19 14:43:24 s2sin55681a7abc10 info Incoming s2s stream quicksy.im->okaris.de closed: No stream features to offer Apr 19 14:43:24 s2sin55681a7abc10 debug Destroying incoming session quicksy.im->okaris.de: No stream features to offer Apr 19 14:43:24 s2sin55681a7abc10 debug s2s disconnected: <nil>-><nil> (connection closed) Apr 19 14:43:24 socket debug server.lua: closed client handler and removed socket from list Apr 19 14:43:24 socket debug server.lua: accepted new client connection from 94.130.132.243:55161 to 5269 Apr 19 14:43:24 s2sin55681a7b80e0 debug Incoming s2s connection Apr 19 14:43:24 runnerSjzGqTK6 debug creating new coroutine Apr 19 14:43:24 s2sin55681a7b80e0 debug Incoming s2s received <stream:stream from='quicksy.im' xml:lang='en' to='conference.okaris.de' version='1.0' xmlns='http://etherx.jabber.org/streams'> Apr 19 14:43:24 s2sin55681a7b80e0 debug Sending[s2sin_unauthed]: <?xml version='1.0'?> Apr 19 14:43:24 s2sin55681a7b80e0 debug Sending[s2sin_unauthed]: <stream:stream xmlns='jabber:server' xml:lang='en' id='a78ef512-24a4-437b-b47a-681113ee8330' to='quicksy.im' xmlns:stream='http://etherx.jabber.org/streams' from='conference.okaris.de' version='1.0'> Apr 19 14:43:24 s2sin55681a7b80e0 warn No stream features to offer, giving up Apr 19 14:43:24 s2sin55681a7b80e0 debug Disconnecting 94.130.132.243[s2sin_unauthed], <stream:error> is: <stream:error><undefined-condition xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text xmlns='urn:ietf:params:xml:ns:xmpp-streams'>No stream features to offer</text></stream:error> Apr 19 14:43:24 s2sin55681a7b80e0 debug Sending[s2sin_unauthed]: <stream:error> Apr 19 14:43:24 s2sin55681a7b80e0 debug Sending[s2sin_unauthed]: </stream:stream> Apr 19 14:43:24 s2sin55681a7b80e0 info Incoming s2s stream quicksy.im->conference.okaris.de closed: No stream features to offer Apr 19 14:43:24 s2sin55681a7b80e0 debug Destroying incoming session quicksy.im->conference.okaris.de: No stream features to offer Apr 19 14:43:24 s2sin55681a7b80e0 debug s2s disconnected: <nil>-><nil> (connection closed) Apr 19 14:43:24 socket debug server.lua: closed client handler and removed socket from list Apr 19 14:44:17 socket debug server.lua: accepted new client connection from 91.33.200.224:36904 to 5222 Apr 19 14:44:17 c2s55681a7cb2d0 info Client connected Apr 19 14:44:17 c2s55681a7cb2d0 debug Client sent opening <stream:stream> to okaris.de Apr 19 14:44:17 c2s55681a7cb2d0 debug Sending[c2s_unauthed]: <?xml version='1.0'?> Apr 19 14:44:17 c2s55681a7cb2d0 debug Sending[c2s_unauthed]: <stream:stream xmlns='jabber:client' xml:lang='en' id='07aa91be-dd99-4b08-969f-9bd5f5eedabd' xmlns:stream='http://etherx.jabber.org/streams' from='okaris.de' version='1.0'> Apr 19 14:44:17 c2s55681a7cb2d0 debug Sent reply <stream:stream> to client Apr 19 14:44:17 c2s55681a7cb2d0 warn No stream features to offer Apr 19 14:44:17 c2s55681a7cb2d0 debug Disconnecting client, <stream:error> is: <stream:error><undefined-condition xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text xmlns='urn:ietf:params:xml:ns:xmpp-streams'>No stream features to proceed with</text></stream:error> Apr 19 14:44:17 c2s55681a7cb2d0 debug Sending[c2s_unauthed]: <stream:error> Apr 19 14:44:17 c2s55681a7cb2d0 debug Sending[c2s_unauthed]: </stream:stream> Apr 19 14:44:17 c2s55681a7cb2d0 debug c2s stream for 91.33.200.224 closed: No stream features to proceed with Apr 19 14:44:17 c2s55681a7cb2d0 debug Destroying session for (unknown) ((unknown)@okaris.de): No stream features to proceed with Apr 19 14:44:17 c2s55681a7cb2d0 info Client disconnected: connection closed Apr 19 14:44:17 c2s55681a7cb2d0 debug Destroying session for (unknown) ((unknown)@(unknown)) Apr 19 14:44:17 socket debug server.lua: closed client handler and removed socket from list Apr 19 14:44:39 mod_posix warn Received SIGTERM Apr 19 14:44:39 startup info Shutting down: Received SIGTERM Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 socket debug server.lua: closed server handler and removed sockets from list Apr 19 14:44:39 general info Shutting down... Apr 19 14:44:39 general info Shutdown status: Cleaning up Apr 19 14:44:39 general info Shutdown complete

  6. Zash on

    Looks like mod_tls is not enabled at all.

  7. Zash on

    Any progress? Is mod_tls enabled and are there certificates configured?

    Changes
    • tags Status-NeedInfo
  8. Zash on

    Closing because we haven't heard anything for a long time.

    Changes
    • tags Status-Invalid

New comment

Not published. Used for spam prevention and optional update notifications.