Project

General

Profile

not-authorized (must bind resource first) in internal packet delivery

Daniele Ricci
Added over 4 years ago

Hello,

I'm experiencing a strange behaviour in my server. I'm using latest git master code, and I receive see strange error packets now and then (leading to lost packets actually).

Let's start from a simple type chat with . This is the log part involved:

2014-12-16 15:20:40.979 [pool-11-thread-1]  ClientConnectionManager.processSocketData()  FINEST: Processing socket data: from=null, to=null, DATA=<message type="chat" id="5hkjn-2" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=198, XMLNS=null, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat from connection: c2s@localhost/198.211.127.209_5999_37.119.243.27_4704
2014-12-16 15:20:40.980 [pool-11-thread-1]  ClientConnectionManager.processSocketData()  FINEST: XMLNS set for packet: from=null, to=null, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat from connection: c2s@localhost/198.211.127.209_5999_37.119.243.27_4704
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : sess-man@localhost
2014-12-16 15:20:40.980 [in_0-message-router]  MessageRouter.processPacket()  FINEST:   1. Packet will be processed by: sess-man@localhost, from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-16 15:20:40.980 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   Received packet: from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-16 15:20:40.981 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   processing packet: from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, connection: XMPPResourceConnection=[user_jid=af31955e620c1f873cf9093d7598b2a3a3a5c28c@prime.kontalk.net/cec5ae5a-b161-4d0b-a531-2bb75569a9f5, packets=4, connectioId=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-16 15:20:40.981 [in_12-sess-man]   PacketDefaultHandler.preprocess()  INFO:     Session details: connectionId=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, sessionId=cec5ae5a-b161-4d0b-a531-2bb75569a9f5
2014-12-16 15:20:40.981 [in_12-sess-man]   PacketDefaultHandler.preprocess()  FINEST:   Session more detais: JID=af31955e620c1f873cf9093d7598b2a3a3a5c28c@prime.kontalk.net/cec5ae5a-b161-4d0b-a531-2bb75569a9f5
2014-12-16 15:20:40.981 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   Packet preprocessed: from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-16 15:20:40.981 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   Preprocess result: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">CData size: 76</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error
2014-12-16 15:20:40.981 [in_12-sess-man]   DomainFilter.filter()              FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, to=sess-man@localhost, DATA=<message type="chat" id="5hkjn-2" xmlns="jabber:client" to="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/></message>, SIZE=220, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-16 15:20:40.981 [in_12-sess-man]   DomainFilter.getDomains()          FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-16 15:20:40.981 [in_12-sess-man]   JabberIqPrivacy.filter()           FINEST:   Checking outbound packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">You must bind the resource first: http://www.xmpp.org/rfcs/rfc3920.html#bind</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error
2014-12-16 15:20:40.982 [in_0-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">You must bind the resource first: http://www.xmpp.org/rfcs/rfc3920.html#bind</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error
2014-12-16 15:20:40.982 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : c2s@localhost/198.211.127.209_5999_37.119.243.27_4704
2014-12-16 15:20:40.982 [in_0-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-16 15:20:40.982 [in_0-message-router]  MessageRouter.processPacket()  FINEST:   1. Packet will be processed by: c2s@localhost, from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">You must bind the resource first: http://www.xmpp.org/rfcs/rfc3920.html#bind</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error
2014-12-16 15:20:40.982 [in_0-c2s]         ClientConnectionManager.processPacket()  FINEST: Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">CData size: 76</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error
2014-12-16 15:20:40.982 [in_0-c2s]         ConnectionManager.writePacketToSocket()  FINEST: c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, type: accept, Socket: ZLIB: TLS: c2s@localhost/198.211.127.209_5999_37.119.243.27_4704 Socket[addr=/37.119.243.27,port=4704,localport=5999], jid: af31955e620c1f873cf9093d7598b2a3a3a5c28c@prime.kontalk.net, Writing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_37.119.243.27_4704, DATA=<message type="error" xmlns="jabber:client" id="5hkjn-2" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><received id="bt3F5B748XNHGDIUOG5HLRDKv95U5w" xmlns="urn:xmpp:receipts"/><error type="auth" code="401"><not-authorized xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/><text xml:lang="en" xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">You must bind the resource first: http://www.xmpp.org/rfcs/rfc3920.html#bind</text></error></message>, SIZE=469, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=error

Seems like PacketDefaultHandler.preprocess() puts this if the resource is not bound yet.

I get this, but I (the recipient of the message stanza) was online and receiving other messages in that moment (no network fault of any kind), how could that happen?

I patched the server with https://projects.tigase.org/issues/2535 to include stanzas in offline storage.


Replies (7)

Avatar?id=6023&size=32x32

Added by Artur Hefczyc TigaseTeam over 4 years ago

Daniele,

From the log you attached, it looks like the sender (not a recipient) of the stanza has not completed all the authentication process. Resource binding in this case. So the message is returned back to the sender with the error.

Added by Daniele Ricci over 4 years ago

Some days ago (but I thought it was a "glitch" and I forgot to investigate further), I received a couple of message stanzas (probably coming from offline storage) right after compression was activated. My client is programmed to send a message receipt () as soon as a message is received (even if the authentication process is not over yet), so this is probably the case. Nonetheless, this is still a server problem which unfortunately I wasn't able to reproduce.

By the way: I'm using SM without resumption, with all the implications caused by Tigase SM behaviour (regularly losing messages on broken connections, I will fix this somehow without using resumption).

Avatar?id=6023&size=32x32

Added by Artur Hefczyc TigaseTeam over 4 years ago

Messages from offline storage are sent once first, initial presence was received after user login. I am not 100% sure about this but I think that a message sent from a user just at the time you are authenticating, may be delivered to the user after successful authentication but before resource binding or anything else. This, most likely can happen, however, chances for this to happen are quite slim.

Added by Daniele Ricci over 4 years ago

Another lost message, this time due to a broken socket. The message got requeued with delivery-error and on second delivery it got blocked by Message.preprocess, thus lost forever:

First message delivery attempt:

2014-12-17 08:26:56.921 [pool-11-thread-1]  ClientConnectionManager.processSocketData()  FINEST: Processing socket data: from=null, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=239, XMLNS=null, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat from connection: c2s@localhost/198.211.127.209_5999_217.200.202.181_1355
2014-12-17 08:26:56.921 [pool-11-thread-1]  ClientConnectionManager.processSocketData()  FINEST: XMLNS set for packet: from=null, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=261, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat from connection: c2s@localhost/198.211.127.209_5999_217.200.202.181_1355
2014-12-17 08:26:56.921 [in_2-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=261, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.921 [in_2-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.921 [in_2-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:26:56.921 [in_2-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:26:56.921 [in_2-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:26:56.922 [in_2-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : sess-man@localhost
2014-12-17 08:26:56.922 [in_2-message-router]  MessageRouter.processPacket()  FINEST:   1. Packet will be processed by: sess-man@localhost, from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=261, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.922 [in_2-sess-man]    SessionManager.processPacket()     FINEST:   Received packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=261, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.922 [in_2-sess-man]    SessionManager.processPacket()     FINEST:   processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=261, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, connection: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=49, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.922 [in_2-sess-man]    PacketDefaultHandler.preprocess()  FINEST:   Setting correct from attribute: e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f
2014-12-17 08:26:56.922 [in_2-sess-man]    StartTLS.preProcess()              FINEST:   VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.922 [in_2-sess-man]    DomainFilter.preProcess()          FINEST:   Processing: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.922 [in_2-sess-man]    DomainFilter.getDomains()          FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.922 [in_2-sess-man]    DomainFilter.preProcess()          FINEST:   DOMAINS setting is: ALL
2014-12-17 08:26:56.922 [in_2-sess-man]    SessionManager.walk()              FINEST:   XMPPProcessorIfc: Message (message)Request: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, conn: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=50, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.923 [message Queue Worker 0]  Message.process()           FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=50, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.923 [message Queue Worker 0]  DomainFilter.filter()       FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.923 [message Queue Worker 0]  DomainFilter.getDomains()   FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.923 [message Queue Worker 0]  JabberIqPrivacy.filter()    FINEST:   Checking outbound packet: from=null, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.923 [message Queue Worker 0]  JabberIqPrivacy.allowed()   FINEST:   Using privcy list: null
2014-12-17 08:26:56.923 [in_1-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.923 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.923 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:26:56.924 [in_1-message-router]  MessageRouter.processPacket()  FINEST:   2. Packet will be processed by: sess-man@localhost, from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.924 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   Received packet: from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.924 [in_5-sess-man]    SessionManager.getXMPPResourceConnection()  FINEST: Searching for resource connection for: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.924 [in_5-sess-man]    SessionManager.getResourceConnection()  FINEST: Session not null, searching session for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.924 [in_5-sess-man]    SessionManager.getResourceConnection()  FINEST: Found session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=15, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false], for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.925 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, connection: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=15, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.925 [in_5-sess-man]    StartTLS.preProcess()              FINEST:   VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.925 [in_5-sess-man]    DomainFilter.preProcess()          FINEST:   Processing: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.925 [in_5-sess-man]    DomainFilter.getDomains()          FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.925 [in_5-sess-man]    DomainFilter.preProcess()          FINEST:   DOMAINS setting is: ALL
2014-12-17 08:26:56.925 [in_5-sess-man]    JabberIqPrivacy.allowed()          FINEST:   Using privcy list: null
2014-12-17 08:26:56.925 [in_5-sess-man]    SessionManager.walk()              FINEST:   XMPPProcessorIfc: Message (message)Request: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, conn: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=16, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.925 [in_5-sess-man]    KontalkPushNotifications.postProcess()  FINEST: Processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.925 [in_5-sess-man]    DomainFilter.filter()              FINEST:   Filtering (packet): from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.925 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   Packet processed by: [message]
2014-12-17 08:26:56.926 [message Queue Worker 0]  Message.process()           FINEST:   Processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=16, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.926 [message Queue Worker 0]  Message.process()           FINEST:   Message to this user, packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=16, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.932 [message Queue Worker 0]  Message.process()           FINEST:   Delivering message, packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, to session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=16, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:26:56.932 [message Queue Worker 0]  DomainFilter.filter()       FINEST:   Filtering (packet): from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.932 [message Queue Worker 0]  DomainFilter.getDomains()   FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:26:56.933 [message Queue Worker 0]  JabberIqPrivacy.filter()    FINEST:   Checking outbound packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.933 [message Queue Worker 0]  JabberIqPrivacy.allowed()   FINEST:   Using privcy list: null
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : c2s@localhost/198.211.127.209_5999_213.203.148.187_49145
2014-12-17 08:26:56.933 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:26:56.934 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   1. Packet will be processed by: c2s@localhost, from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.934 [in_3-c2s]         ClientConnectionManager.processPacket()  FINEST: Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.934 [in_3-c2s]         ConnectionManager.writePacketToSocket()  FINEST: c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, type: accept, Socket: ZLIB: TLS: c2s@localhost/198.211.127.209_5999_213.203.148.187_49145 Socket[addr=/213.203.148.187,port=49145,localport=5999], jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, Writing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:26:56.932 [in_2-sess-man]    KontalkPushNotifications.postProcess()  FINEST: Processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.935 [in_2-sess-man]    DomainFilter.filter()              FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_217.200.202.181_1355, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/></message>, SIZE=344, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:26:56.935 [in_2-sess-man]    SessionManager.processPacket()     FINEST:   Packet processed by: [message]

Second message delivery attempt (it's unclear to me how the packet was requeued this time, probably by SM?):

2014-12-17 08:27:05.064 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   Received packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=206, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:05.064 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net"><body>CData size: 4</body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=206, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, connection: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=8, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.064 [in_12-sess-man]   PacketDefaultHandler.preprocess()  FINEST:   Setting correct from attribute: e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f
2014-12-17 08:27:05.064 [in_12-sess-man]   StartTLS.preProcess()              FINEST:   VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.064 [in_12-sess-man]   DomainFilter.preProcess()          FINEST:   Processing: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:05.064 [in_12-sess-man]   DomainFilter.getDomains()          FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.064 [in_12-sess-man]   DomainFilter.preProcess()          FINEST:   DOMAINS setting is: ALL
2014-12-17 08:27:05.064 [in_12-sess-man]   SessionManager.walk()              FINEST:   XMPPProcessorIfc: Message (message)Request: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, conn: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=9, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.066 [message Queue Worker 0]  Message.process()           FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f, packets=9, connectioId=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.066 [message Queue Worker 0]  DomainFilter.filter()       FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:05.066 [message Queue Worker 0]  DomainFilter.getDomains()   FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.066 [message Queue Worker 0]  JabberIqPrivacy.filter()    FINEST:   Checking outbound packet: from=null, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.066 [message Queue Worker 0]  JabberIqPrivacy.allowed()   FINEST:   Using privcy list: null
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:27:05.067 [in_1-message-router]  MessageRouter.processPacket()  FINEST:   2. Packet will be processed by: sess-man@localhost, from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.067 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   Received packet: from=sess-man@localhost, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.068 [in_5-sess-man]    SessionManager.getXMPPResourceConnection()  FINEST: Searching for resource connection for: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.068 [in_5-sess-man]    SessionManager.getResourceConnection()  FINEST: Session not null, searching session for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.068 [in_5-sess-man]    SessionManager.getResourceConnection()  FINEST: Found session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=17, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false], for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.068 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, connection: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=17, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.068 [in_5-sess-man]    StartTLS.preProcess()              FINEST:   VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.068 [in_5-sess-man]    DomainFilter.preProcess()          FINEST:   Processing: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.068 [in_5-sess-man]    DomainFilter.getDomains()          FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.068 [in_5-sess-man]    DomainFilter.preProcess()          FINEST:   DOMAINS setting is: ALL
2014-12-17 08:27:05.068 [in_5-sess-man]    JabberIqPrivacy.allowed()          FINEST:   Using privcy list: null
2014-12-17 08:27:05.068 [in_5-sess-man]    SessionManager.walk()              FINEST:   XMPPProcessorIfc: Message (message)Request: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, conn: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=18, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.068 [message Queue Worker 0]  Message.process()           FINEST:   Processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=18, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.068 [message Queue Worker 0]  Message.process()           FINEST:   Message to this user, packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, for session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=18, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.069 [message Queue Worker 0]  Message.process()           FINEST:   Delivering message, packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat, to session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=18, connectioId=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:05.069 [message Queue Worker 0]  DomainFilter.filter()       FINEST:   Filtering (packet): from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.069 [message Queue Worker 0]  DomainFilter.getDomains()   FINEST:   Domains read from user session: ALL for VHost: Domain: prime.kontalk.net, enabled: true, anonym: false, register: true, maxusers: 0, tls: true, s2sSecret: 0fd0fbfa-c504-4b2e-97d9-dfe4638a74b6, domainFilter: ALL, domainFilterDomains: null, c2sPortsAllowed: null, saslAllowedMechanisms: null
2014-12-17 08:27:05.069 [message Queue Worker 0]  JabberIqPrivacy.filter()    FINEST:   Checking outbound packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.069 [message Queue Worker 0]  JabberIqPrivacy.allowed()   FINEST:   Using privcy list: null
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:27:05.069 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : c2s@localhost/198.211.127.209_5999_213.203.148.187_49145
2014-12-17 08:27:05.070 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:05.070 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   1. Packet will be processed by: c2s@localhost, from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.070 [in_3-c2s]         ClientConnectionManager.processPacket()  FINEST: Processing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.070 [in_3-c2s]         ConnectionManager.writePacketToSocket()  FINEST: c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, type: accept, Socket: ZLIB: TLS: c2s@localhost/198.211.127.209_5999_213.203.148.187_49145 Socket[addr=/213.203.148.187,port=49145,localport=5999], jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, Writing packet: from=sess-man@localhost, to=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.071 [in_5-sess-man]    KontalkPushNotifications.postProcess()  FINEST: Processing packet: from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.071 [in_5-sess-man]    DomainFilter.filter()              FINEST:   Filtering (packet): from=sess-man@localhost, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=chat
2014-12-17 08:27:05.071 [in_5-sess-man]    SessionManager.processPacket()     FINEST:   Packet processed by: [message]
2014-12-17 08:27:05.071 [in_12-sess-man]   KontalkPushNotifications.postProcess()  FINEST: Processing packet: from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:05.071 [in_12-sess-man]   DomainFilter.filter()              FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_217.200.202.181_57071, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/></message>, SIZE=289, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:05.071 [in_12-sess-man]   SessionManager.processPacket()     FINEST:   Packet processed by: [message]

Third message delivery attempt (with delivery-error):

2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.765 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:45.766 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:45.766 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], basename: kontalk.net
2014-12-17 08:27:45.766 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   2. Packet will be processed by: sess-man@localhost, from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.processPacket()     FINEST:   Received packet: from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.getXMPPResourceConnection()  FINEST: Searching for resource connection for: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.getResourceConnection()  FINEST: Session not null, searching session for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.getResourceConnection()  FINEST: Found session: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=4, connectioId=c2s@localhost/198.211.127.209_5999_91.253.3.160_16960, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false], for jid: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.processPacket()     FINEST:   processing packet: from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>CData size: 4</body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat, connection: XMPPResourceConnection=[user_jid=e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net/f1ff7622ccb9e8dd, packets=4, connectioId=c2s@localhost/198.211.127.209_5999_91.253.3.160_16960, domain=prime.kontalk.net, authState=AUTHORIZED, isAnon=false, isTmp=false]
2014-12-17 08:27:45.766 [in_7-sess-man]    SessionManager.processPacket()     FINEST:   Packet blocked by: message, packetfrom=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.766 [in_7-sess-man]    DomainFilter.filter()              FINEST:   Filtering (packet): from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=sess-man@localhost, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><active xmlns="http://jabber.org/protocol/chatstates"/><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=402, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.767 [in_7-sess-man]    SessionManager.processPacket()     FINEST:   Packet processed by: [message]
2014-12-17 08:27:45.767 [in_3-message-router]  MessageRouter.processPacket()  FINEST:   Processing packet: from=c2s@localhost/198.211.127.209_5999_213.203.148.187_49145, to=null, DATA=<message type="chat" id="O86j2kyYN93trWR6YgchDFD0j0dTN1" xmlns="jabber:client" to="e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net" from="e73ea3be23d0449597a82c62ed981f584a5c181b@prime.kontalk.net/eb2e56271013472f"><body>:-) </body><request xmlns="urn:xmpp:receipts"/><delivery-error xmlns="http://tigase.org/delivery-error"/></message>, SIZE=347, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=chat
2014-12-17 08:27:45.767 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net
2014-12-17 08:27:45.767 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [monitor@localhost, bosh@localhost, c2s@localhost, sess-man@localhost, vhost-man@localhost, amp@localhost, s2s@localhost, ws2s@localhost, stats@localhost, basic-conf@localhost, push@localhost]; trying VHost lookup
2014-12-17 08:27:45.767 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for map: [c2s, basic-conf, s2s, push, bosh, vhost-man, sess-man, ws2s, message-router, stats, amp, monitor], for all VHosts: [prime.kontalk.net]; trying other forms of addressing
2014-12-17 08:27:45.767 [in_3-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: e60e66abd1c33e1cff803b553dcddc2a00754d80@prime.kontalk.net, for comp: [
Avatar?id=6023&size=32x32

Added by Artur Hefczyc TigaseTeam about 4 years ago

Lost messages and packets in general due to broken socket can happen. There is no mechanism in the XMPP core spec to prevent that. To ensure QoS, you need additional mechanisms like stream management extension, AMP, message receipts, etc.... Usually a single mechanism is not enough as each of them work on a different level and helps with a part of the problem only.

And broken sockets do happen, very often, especially in case of mobile devices. They can silently lost connection without either a client or the server noticing.

Added by Daniele Ricci about 4 years ago

Indeed, but my plan is to just use SM without resume and client delivery receipts, so I've removed delivery-error preprocessing (1) for now to see what happens. I prefer duplicate messages rather than losing them forever.

(1) https://github.com/kontalk/tigase-server/commit/12ec9cc95124164d94af4091a991b0da2f1f32b5

Added by Daniele Ricci about 4 years ago

Applying the above modifications didn't get the desired result: I've got a lost message redelivered from offline storage and then lost forever because it was sent to a broken socket.

How can this be possible? I mean if the server doesn't receive ack for messages, shouldn't it queue them again by using sendErrorsForQueuedPackets when the connection breaks?

Merry Christmas

    (1-7/7)