Tigase XMPP Server Board

Tigase server administration: RE: Tigase High Performance Configurations

Thu, 12/18/2014 - 21:27

Thank you for the hint. This certainly makes sense. I have also updated our code, however, I changed your code a bit to:
ssc.socket().bind(isa, (int) (port_throttling));
So the backlog is set to a value of open connection throttling property.

Categories: Tigase Forums

Tigase server development: RE: not-authorized (must bind resource first) in internal packet delivery

Thu, 12/18/2014 - 21:01

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.

Categories: Tigase Forums

Tigase server development: RE: Best Strategy for Timeouts

Thu, 12/18/2014 - 20:49

Ok, thank you for additional information. Now I have a better picture of the whole thing. One of our customers has implemented similar system for strict QoS/QA. They make payment over XMPP so they need to be 100% sure that every single message is delivered.

My suggestion to you would be to implement a similar thing based on the AMP implementation in Tigase. If you use AMP than you will tremendously reduce amount of work you require and you will preserve a lot of flexibility. Tigase's AMP works quite well and is optimized for a high traffic. Plus, what's even more important, it is implemented in plugin-component architecture which allows you to offload the hard work on a separate server.

If you look closely into the AMP specification and Tigase implementation you can see that AMP has actions and conditions. In Tigase these are plugins, so you can easily add more actions and conditions to plug your own logic and handlers. Even now, there are actions and conditions which can deal with messages not delivered in specified time. It does not fulfill all your requirements but it's a good start.
And then you have this great flexibility as you can more or less control the how message is handled from a client side by putting AMP payload properties.

Categories: Tigase Forums

Tigase server administration: RE: Tigase using too much memory

Thu, 12/18/2014 - 17:38
A few questions:
  1. How do you know/monitor memory usage?
  2. If this is system top command which column do you take into consideration?
  3. Do you run Tigase inside a VM? We had experience with some VMs and JVMs that memory settings were not respected and JVM used much more RAM that it was allowed to (actually all the available RAM).
  4. Do you check what memory usage is reported by JVM or Tigase in Tigase's metrics?
Categories: Tigase Forums

Tigase server administration: Issue with documentation

Thu, 12/18/2014 - 08:44

Hi there,

could you please fix this page http://docs.tigase.org/tigase-server/5.2.0/admin_guide/html/
especially, sections with "External Component" and "Virtual Component"

a bit hard to read

thanks

Categories: Tigase Forums

Tigase server administration: RE: Tigase High Performance Configurations

Thu, 12/18/2014 - 08:28

Hi,

We increased ServerSocketChannel in ConnectionOpenThread class backlog while bind to 2048 which solved our problem.

ssc.socket().bind(isa, 2048);

Thanks
Kulshreshth

Categories: Tigase Forums

Tigase server administration: Tigase using too much memory

Thu, 12/18/2014 - 08:20

Hi,

we have allocated 8gb as max memory in tigase.conf file, but we are observing that the memory goes beyond 12GB. There are around 60k connections and around 30k messages sent per day. It doesn't go out of memory, but we are unable to find out the reason for this huge memory usage. Actual memory used is around 5GB and we are using java 1.7, garbage collector is G1GC. Please guide us.

Regards,
Kulshreshth

Categories: Tigase Forums

Tigase server administration: RE: File transfer issue with socks5

Thu, 12/18/2014 - 06:17

No, iam not prepared. but why we need database configuration

In configuration file if i mention under commnad i think we need datbase is it correct?

proxy/socks5-repo-cls=tigase.socks5.repository.JDBCSocks5Repository

tigase.socks5.verirepository.JDBCSocks5Repository (requires database preparation - see above)

Categories: Tigase Forums

Tigase server administration: File transfer issue

Thu, 12/18/2014 - 06:11

Hi Team,

iam trying tranfer file using socks5 but unable to transfer file .
i have attached log files please have a look and kindly help me.

init properties :
--comp-class-1 = tigase.muc.cluster.MUCComponentClustered
--virt-hosts = tgs.framily.in
--user-db-uri = jdbc:mysql://localhost/tigasedb?user=tigase&password=tigase12
--user-db = mysql
--admins =
--comp-name-6 = message-archive
--comp-name-5 = rest
--comp-name-4 = stun
--comp-name-3 = proxy
config-type = --gen-config-def
--comp-name-2 = pubsub
--comp-name-1 = muc
--cluster-mode = false
--sm-plugins = +message-archive-xep-0136,+jabber:iq:auth,+urn:ietf:params:xml:ns:xmpp-sasl,+urn:ietf:params:xml:ns:xmpp-bind,+urn:ietf:params:xml:ns:xmpp-session,+jabber:iq:register,+jabber:iq:roster,+presence,+jabber:iq:privacy,+jabber:iq:version,+http://jabber.org/protocol/stats,+starttls,+msgoffline,+vcard-temp,+http://jabber.org/protocol/commands,+jabber:iq:private,+urn:xmpp:ping,+basic-filter,+domain-filter,+pep,+zlib
--comp-class-6 = tigase.archive.MessageArchiveComponent
--debug = server,xmpp.impl,db
--comp-class-5 = tigase.http.rest.RestMessageReceiver
--comp-class-4 = tigase.stun.StunComponent
--comp-class-3 = tigase.socks5.Socks5ProxyComponent
--comp-class-2 = tigase.pubsub.cluster.PubSubComponentClustered
--monitoring=jmx:9050,http:9080,snmp:9060
proxy/component-id[S]=
proxy/verifier-class=tigase.socks5.verifiers.LimitsVerifier
proxy/socks5-repo-cls=tigase.socks5.repository.JDBCSocks5Repository
proxy/repo-url=jdbc:mysql://localhost:3306/tigasedb?user=tigase&password=tigase12

Categories: Tigase Forums

Tigase server administration: RE: File transfer issue with socks5

Thu, 12/18/2014 - 06:07

Have you prepared database for Socks5 component (i.e. imported proper schema file: https://projects.tigase.org/projects/socks5/repository/revisions/master/show/database)?

Categories: Tigase Forums

Tigase server administration: RE: File transfer issue with socks5

Thu, 12/18/2014 - 05:27

Hi team,

first of all thanks to reply.

I made few changes in inipropeties:

--cluster-mode = false
config-type = --gen-config-def
--cluster-nodes = tigaseserver
--debug = server,xmpp.impl,db,cluster
--user-db = derby
--admins =
--user-db-uri = jdbc:derby:/usr/local/Tigase-5.1.0-beta4-b2836/tigasedb
--virt-hosts = framily.in
--comp-class-1 = tigase.muc.MUCComponent
--comp-name-1 = muc
--sm-plugins = +jabber:iq:auth,+urn:ietf:params:xml:ns:xmpp-sasl,+urn:ietf:params:xml:ns:xmpp-bind,+urn:ietf:params:xml:ns:xmpp-session,+jabber:iq:register,+jabber:iq:roster,+presence,+jabber:iq:privacy,+jabber:iq:version,+http://jabber.org/protocol/stats,+starttls,+msgoffline,+vcard-temp,+http://jabber.org/protocol/commands,+jabber:iq:private,+urn:xmpp:ping,+basic-filter,+domain-filter,+pep,-zlib
--comp-name-1=proxy
--comp-class-1=tigase.socks5.Socks5ProxyComponent
--comp-name-3 = message-archive
--comp-class-3 = tigase.archive.MessageArchiveComponent
proxy/component-id[S]=
--monitoring=jmx:9050,http:9080,snmp:9060
proxy/verifier-class=tigase.socks5.verifiers.DummyVerifier

after that error count decreased :

$: load-errors
awaiting response...
Errors: 1
null
'TIGSOCKS5CREATEUID' is not recognized as a function or procedure.: 2
2014-12-18 07:59:29 Socks5ProxyComponent.setProperties() SEVERE: An error initializing data repository pool:
tigase.db.DBInitException: 'TIGSOCKS5CREATEUID' is not recognized as a function or procedure.
at tigase.socks5.repository.JDBCSocks5Repository.initRepository(JDBCSocks5Repository.java:142)
at tigase.socks5.Socks5ProxyComponent.setProperties(Socks5ProxyComponent.java:311)
at tigase.conf.ConfiguratorAbstract.setup(ConfiguratorAbstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorAbstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorAbstract.java)
at tigase.server.AbstractComponentRegistrator.addComponent(AbstractComponentRegistrator.java)
at tigase.server.MessageRouter.addComponent(MessageRouter.java)
at tigase.server.MessageRouter.addRouter(MessageRouter.java)
at tigase.server.MessageRouter.setProperties(MessageRouter.java)
at tigase.conf.ConfiguratorAbstract.setup(ConfiguratorAbstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorAbstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorAbstract.java)
at tigase.server.AbstractComponentRegistrator.addComponent(AbstractComponentRegistrator.java)
at tigase.server.MessageRouter.addRegistrator(MessageRouter.java)
at tigase.server.MessageRouter.setConfig(MessageRouter.java)
at tigase.server.XMPPServer.main(XMPPServer.java)

Categories: Tigase Forums

Tigase server administration: RE: File transfer issue with socks5

Thu, 12/18/2014 - 04:22

Which Tigase version do you use? (DB string would indicate version 5.1.0-beta4, but that may be just remnant). Could you try one of the latest final versions (5.2.3 for example)?

Also, exception:
2014-12-18 00:51:05 SaslAuth.process() INFO: Authentication failed: tigase.xmpp.NotAuthorizedException: Authorization failed
indicates that there is problem with authenticating users.

Categories: Tigase Forums

Tigase server administration: RE: File transfer issue with socks5

Thu, 12/18/2014 - 02:33

My Server init properties:

--cluster-mode = false
config-type = --gen-config-def
--cluster-nodes = tigaseserver
--debug = server,xmpp.impl,db,cluster
--user-db = derby
--admins =
--user-db-uri = jdbc:derby:/usr/local/Tigase-5.1.0-beta4-b2836/tigasedb
--virt-hosts = framily.in
--comp-class-1 = tigase.muc.MUCComponent
--comp-name-1 = muc
--sm-plugins = +jabber:iq:auth,+urn:ietf:params:xml:ns:xmpp-sasl,+urn:ietf:params:xml:ns:xmpp-bind,+urn:ietf:params:xml:ns:xmpp-session,+jabber:iq:register,+jabber:iq:roster,+presence,+jabber:iq:privacy,+jabber:iq:version,+http://jabber.org/protocol/stats,+starttls,+msgoffline,+vcard-temp,+http://jabber.org/protocol/commands,+jabber:iq:private,+urn:xmpp:ping,+basic-filter,+domain-filter,+pep,-zlib
--comp-name-1=proxy
--comp-class-1=tigase.socks5.Socks5ProxyComponent
--comp-name-3 = message-archive
--comp-class-3 = tigase.archive.MessageArchiveComponent
proxy/component-id[S]=
--monitoring=jmx:9050,http:9080,snmp:9060

Categories: Tigase Forums

Tigase server administration: File transfer issue with socks5

Thu, 12/18/2014 - 02:27

Hi Team,

I am trying to file transfer using SOCK5 proxy component but unable to transfer file .I got below errors in my console log .

I have attached console.log and log files .Please have a look and kindly help me .

regards
sridhar

Errors: 8
null
java.lang.NullPointerException: 4
2014-12-17 08:45:31 SocketThread$ResultsListener.run() WARNING: Proto col execution exception.
java.util.concurrent.ExecutionException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:188)
at tigase.net.SocketThread$ResultsListener.run(SocketThread.java )
Caused by: java.lang.NullPointerException
at tigase.socks5.verifiers.LimitsVerifier.getLimits(LimitsVerifi er.java:259)
at tigase.socks5.verifiers.LimitsVerifier.updateTransfer(LimitsV erifier.java:144)
at tigase.socks5.Socks5ProxyComponent.serviceStopped(Socks5Proxy Component.java:229)
at tigase.socks5.Socks5ProxyComponent.serviceStopped(Socks5Proxy Component.java:35)
at tigase.net.IOService.forceStop(IOService.java)
at tigase.socks5.Socks5IOService.forceStop(Socks5IOService.java: 199)
at tigase.net.IOService.readBytes(IOService.java)
at tigase.socks5.Socks5IOService.readBytes(Socks5IOService.java: 158)
at tigase.socks5.Socks5IOService.processSocketData(Socks5IOServi ce.java:234)
at tigase.net.IOService.call(IOService.java)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:140)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:17)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors .java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolE xecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPool Executor.java:615)
at java.lang.Thread.run(Thread.java:745)
null
Authorization failed: 11
2014-12-18 02:40:40 SaslAuth.process() INFO: Authe ntication failed:
tigase.xmpp.NotAuthorizedException: Authorization failed
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
Caused by: tigase.db.UserNotFoundException: User does not exist: temp6@f ramily.in, in database: jdbc:derby:/usr/local/Tigase-5.1.0-beta4-b2836/tigasedb
at tigase.db.jdbc.TigaseCustomAuth.userLoginAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.plainAuth(TigaseCustomAuth.ja va)
at tigase.db.jdbc.TigaseCustomAuth.saslPlainAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.otherAuth(TigaseCustomAuth.ja va)
at tigase.db.AuthRepositoryMDImpl.otherAuth(AuthRepositoryMDImpl .java)
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
null
java.lang.NullPointerException: 10
2014-12-17 10:10:16 SocketThread$ResultsListener.run() WARNING: Proto col execution exception.
java.util.concurrent.ExecutionException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:188)
at tigase.net.SocketThread$ResultsListener.run(SocketThread.java )
Caused by: java.lang.NullPointerException
at tigase.socks5.verifiers.LimitsVerifier.getLimits(LimitsVerifi er.java:259)
at tigase.socks5.verifiers.LimitsVerifier.updateTransfer(LimitsV erifier.java:144)
at tigase.socks5.Socks5ProxyComponent.socketDataProcessed(Socks5 ProxyComponent.java:246)
at tigase.socks5.Socks5IOService.processSocketData(Socks5IOServi ce.java:289)
at tigase.net.IOService.call(IOService.java)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:140)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:17)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors .java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolE xecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPool Executor.java:615)
at java.lang.Thread.run(Thread.java:745)
null
java.lang.NullPointerException: 6
2014-12-17 06:53:52 SocketThread$ResultsListener.run() WARNING: Proto col execution exception.
java.util.concurrent.ExecutionException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:188)
at tigase.net.SocketThread$ResultsListener.run(SocketThread.java )
Caused by: java.lang.NullPointerException
at tigase.socks5.verifiers.LimitsVerifier.getLimits(LimitsVerifi er.java:259)
at tigase.socks5.verifiers.LimitsVerifier.updateTransfer(LimitsV erifier.java:144)
at tigase.socks5.Socks5ProxyComponent.serviceStopped(Socks5Proxy Component.java:229)
at tigase.socks5.Socks5ProxyComponent.serviceStopped(Socks5Proxy Component.java:35)
at tigase.net.IOService.forceStop(IOService.java)
at tigase.socks5.Socks5IOService.processSocketData(Socks5IOServi ce.java:230)
at tigase.net.IOService.call(IOService.java)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:140)
at tigase.socks5.Socks5IOService.call(Socks5IOService.java:17)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors .java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolE xecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPool Executor.java:615)
at java.lang.Thread.run(Thread.java:745)
null
Authorization failed: 1
2014-12-18 00:51:05 SaslAuth.process() INFO: Authe ntication failed:
tigase.xmpp.NotAuthorizedException: Authorization failed
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
Caused by: tigase.db.UserNotFoundException: User does not exist: admin@f ramily.in, in database: jdbc:derby:/usr/local/Tigase-5.1.0-beta4-b2836/tigasedb
at tigase.db.jdbc.TigaseCustomAuth.userLoginAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.plainAuth(TigaseCustomAuth.ja va)
at tigase.db.jdbc.TigaseCustomAuth.saslPlainAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.otherAuth(TigaseCustomAuth.ja va)
at tigase.db.AuthRepositoryMDImpl.otherAuth(AuthRepositoryMDImpl .java)
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
null
Session has not been yet authorised.: 1
2014-12-18 02:38:40 Presence.process() FINEST: prese nce problem...
tigase.xmpp.NotAuthorizedException: Session has not been yet authorised.
at tigase.xmpp.XMPPResourceConnection.isUserId(XMPPResourceConne ction.java)
at tigase.xmpp.impl.roster.RosterAbstract.getPresenceType(Roster Abstract.java)
at tigase.xmpp.impl.Presence.process(Presence.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
null
Authorization failed: 1
2014-12-17 10:16:45 SaslAuth.process() INFO: Authe ntication failed:
tigase.xmpp.NotAuthorizedException: Authorization failed
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
Caused by: tigase.db.UserNotFoundException: User does not exist: venu@fr amily.in, in database: jdbc:derby:/usr/local/Tigase-5.1.0-beta4-b2836/tigasedb
at tigase.db.jdbc.TigaseCustomAuth.userLoginAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.plainAuth(TigaseCustomAuth.ja va)
at tigase.db.jdbc.TigaseCustomAuth.saslPlainAuth(TigaseCustomAut h.java)
at tigase.db.jdbc.TigaseCustomAuth.otherAuth(TigaseCustomAuth.ja va)
at tigase.db.AuthRepositoryMDImpl.otherAuth(AuthRepositoryMDImpl .java)
at tigase.xmpp.RepositoryAccess.loginOther(RepositoryAccess.java )
at tigase.xmpp.XMPPResourceConnection.loginOther(XMPPResourceCon nection.java)
at tigase.xmpp.impl.SaslAuth.process(SaslAuth.java)
at tigase.server.xmppsession.SessionManager$ProcessorWorkerThrea d.process(SessionManager.java)
at tigase.util.WorkerThread.run(WorkerThread.java)
null
'TIGSOCKS5CREATEUID' is not recognized as a function or proced ure.: 2
2014-12-17 05:10:36 Socks5ProxyComponent.setProperties() SEVERE: An er ror initializing data repository pool:
tigase.db.DBInitException: 'TIGSOCKS5CREATEUID' is not recogni zed as a function or procedure.
at tigase.socks5.repository.JDBCSocks5Repository.initRepository( JDBCSocks5Repository.java:142)
at tigase.socks5.Socks5ProxyComponent.setProperties(Socks5ProxyC omponent.java:311)
at tigase.conf.ConfiguratorAbstract.setup(ConfiguratorAbstract.j ava)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorA bstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorA bstract.java)
at tigase.server.AbstractComponentRegistrator.addComponent(Abstr actComponentRegistrator.java)
at tigase.server.MessageRouter.addComponent(MessageRouter.java)
at tigase.server.MessageRouter.addRouter(MessageRouter.java)
at tigase.server.MessageRouter.setProperties(MessageRouter.java)
at tigase.conf.ConfiguratorAbstract.setup(ConfiguratorAbstract.j ava)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorA bstract.java)
at tigase.conf.ConfiguratorAbstract.componentAdded(ConfiguratorA bstract.java)
at tigase.server.AbstractComponentRegistrator.addComponent(Abstr actComponentRegistrator.java)
at tigase.server.MessageRouter.addRegistrator(MessageRouter.java )
at tigase.server.MessageRouter.setConfig(MessageRouter.java)
at tigase.server.XMPPServer.main(XMPPServer.java)

Categories: Tigase Forums

Tigase server administration: RE: Having {--virt-hosts=domain.net:-anon:register}; Can we filter...

Wed, 12/17/2014 - 01:32

The second one will disable ANONYMOUS globally, therefore the first one is correct.

Categories: Tigase Forums

Tigase server development: RE: not-authorized (must bind resource first) in internal packet delivery

Wed, 12/17/2014 - 01:18

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: [

Categories: Tigase Forums

Tigase server development: RE: Best Strategy for Timeouts

Wed, 12/17/2014 - 01:05

Hi Artur,

Here are my answers for your questions:

1. I need it because I have business logic to be activated on timeouts
2. definitely critical, I mean, if the message can't be delivered, it's not a problem at all, but I need to be aware of that to trigger a reaction in the system. This is a requirement we can't relax. If with tolerance you mean if it needs to be precise to the microsec, no, but of course I have to rely on this feature, I just can't have the check performed 10 minutes later, that would be totally pointless !
3. yes I am sure, because that's exactly the scenario I want to identify, a non-connected client which can't confirm the reception. After a timeout I want the system to be aware I can't deliver it, and activate some "reparation" logic. If I shift this on client side, I fall back in XEP-0079, meaning that when the client reconnects says "Oh, I read the message, but it expired". When ? Could be one second after the timeout, 10 seconds after, the day after... that's not the information I need.

I have complete access and control on the client (we implement it) but the scenario is different and we need something server-side.

BTW, I was looking at the code and I found out a nice inner class that looks very close to what I would like to implement : PacketReceiverTask in tigase.server.AbstractMessageReceiver

In particular, I was thinking about

  1. intercept a XEP-0079 packet with a plugin
  2. forward it to a component
  3. use addOutPacketWithTimeout with the original packet and implementing my reaction logic in the handler.

Does it make sense to you ? Since this is a requirement, I am aware that the system load and complexity will grow up, but that is not a problem

Thanks in advance,
Luca

Categories: Tigase Forums

Tigase server administration: RE: Having {--virt-hosts=domain.net:-anon:register}; Can we filter...

Tue, 12/16/2014 - 23:15

Thank you for reply.

If I want to disable such authentication on VHost level; May I ask which two bellow option is proper one? or (Should I use both):
--virt-hosts=domain.net:-anon:register
--vhost-anonymous-enabled = false

Thank you again for help.

Categories: Tigase Forums

Tigase server development: RE: Run custom SQL query in tigase component

Tue, 12/16/2014 - 16:51

There is an API for that in DataRepository interface. Take a look at class CounterDataLogger for code example.

Categories: Tigase Forums

Tigase server development: RE: Got a c2s/Socket Overflow, while in/out queue wait show zero values

Tue, 12/16/2014 - 16:30

c2s component in/out queues are high level queues keeping XMPP packets for all connections. They can overflow if the overall traffic is so high that it cannot cope with distributing packets to individual connections. This does not happen though.

The other level of buffering, lower and closer to network layer is in the Socket object which queues buffers (binary data) for an individual connection. This can get overfilled when amount of data/traffic for individual connection is higher than it can be pushed. There are 2 possible reasons for this to happen:
  1. Settings for c2s connections are optimized for a low traffic per connection. This is to reduce resources usage per connection and normally works very well for a typical XMPP service as people usually do not generate high traffic on their connections. They do not send high number of messages per second.
  2. Most of Amazon EC2 VMs provide you with very poor I/O performance. You need to check the I/O throughput on your instance and try a different option with a higher I/O throughput. For high traffic installations we recommend EC2 instances with the highest I/O throughput that are offered.
Categories: Tigase Forums

Pages

Get in touch

We provide software products, consulting and custom development services

Tigase, Inc.
100 Pine Street, Suite 1250
San Francisco, CA 94111, USA
Phone: (415) 315 9771

Follow us on:

Twitter

  • @tigase Is Desktop Sharing with Tigase XMPP Server possible? 2 weeks 12 hours ago
  • Tigase Messenger.To enable or disable the account, please just type and hold on the selected account name. http://t.co/HIOjY6Zkyq 2 weeks 19 hours ago
Back to Top