Projects tigase _server server-core Issues #1320
"Missing 'to' attribute, ignoring packet" for s2s sasl-external success (#1320)
Closed
wojciech.kapcia@tigase.net opened 2 years ago
[2022-04-26 04:44:46:217] [FINEST  ] [              in_1-s2s ] SaslExternal.process()           : Received auth request: from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="EXTERNAL">amFiYmVyLmNhbHl4aW5zdGl0dXRlLm9yZw==</auth>, SIZE=111, XMLNS=urn:ietf:params:xml:ns:xmpp-sasl, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=null, STABLE_ID=null [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINEST  ] [              in_1-s2s ] SaslExternal.processAuth()       : Trust: trusted for peer certificate: CN=jabber.calyxinstitute.org, AltNames: [jabber.calyxinstitute.org] [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINEST  ] [              in_1-s2s ] SaslExternal.processAuth()       : Making connection authenticated. cid=tigase.im@jabber.calyxinstitute.org [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINE    ] [              in_1-s2s ] AuthenticatorSelectorManager.authenticateConnection(): Authenticating connection [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINER   ] [              in_1-s2s ] CIDConnections.connectionAuthenticated(): Connection is authenticated. Direction: BOTH [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINEST  ] [              in_1-s2s ] S2SIOService.addCID()            : Adding CID to authenticated: tigase.im@jabber.calyxinstitute.org [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINEST  ] [              in_1-s2s ] ConnectionManager.writePacketsToSocket(): Writing packet: from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>, SIZE=51, XMLNS=urn:ietf:params:xml:ns:xmpp-sasl, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=null, STABLE_ID=null [CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]]
[2022-04-26 04:44:46:218] [FINEST  ] [              in_1-s2s ] ConnectionManager$ServiceConnectedTimer.cancel(): Missing service connected timer task: CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]
[2022-04-26 04:44:46:219] [FINE    ] [              in_1-s2s ] ConnectionManager.serviceStopped(): [[s2s]] Connection stopped: CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]
[2022-04-26 04:44:46:219] [FINEST  ] [              in_1-s2s ] ConnectionManager.serviceStopped(): [[s2s]] processing undelivered packets: 1
[2022-04-26 04:44:46:219] [INFO    ] [              in_1-s2s ] S2SConnectionManager.serviceStopped(): [[s2s]] S2S Connection stopped: CID: tigase.im@jabber.calyxinstitute.org, IN: 1, OUT: 1, authenticated: true, remote-session-id: 83496935-4344-42da-984f-b393ee1efeec, streamNegotiationCompleted: false, jid: null, S2SIOService, UniqueId: 192.168.80.2_5269_172.31.14.247_31066, type: <- incoming (accept), TLS: SocketIO, ID: tigase.im@jabber.calyxinstitute.org/707b8849-48c8-490c-a4f6-a961f1772da4, disconnected Socket[addr=/172.31.14.247,port=31066,localport=5269]
[2022-04-26 04:44:46:219] [FINEST  ] [              in_1-s2s ] S2SConnectionManager.processPacket(): Processing packet: from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>, SIZE=51, XMLNS=urn:ietf:params:xml:ns:xmpp-sasl, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=null, STABLE_ID=null
[2022-04-26 04:44:46:219] [WARNING ] [              in_1-s2s ] S2SConnectionManager.processPacket(): Missing 'to' attribute, ignoring packet...from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/>, SIZE=51, XMLNS=urn:ietf:params:xml:ns:xmpp-sasl, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=null, STABLE_ID=null
 This most likely happens due to missconfiguration of components domain names.
wojciech.kapcia@tigase.net commented 2 years ago

Issue similar to #issue #1056, I extended the check for other processors (not only stream features).

issue 1 of 1
Type
Bug
Priority
Normal
Assignee
Version
tigase-server-8.3.0
Spent time
5h 15m
Issue Votes (0)
Watchers (0)
Reference
tigase/_server/server-core#1320
Please wait...
Page is in error, reload to recover