Project

General

Profile

Task #4288

Examples for direct presence and handling incomming message

Added by Eric Dziewa over 2 years ago. Updated over 2 years ago.

Status:
Closed
Priority:
Normal
Assignee:
Target version:
-
Start date:
2016-06-29
Due date:
2016-09-14
% Done:

0%

Estimated time:

Description

Need a Groovy example in the wiki for sending a directed presence, and receiving a message.


Related issues

Related to Tigase XMPP Server - Bug #4365: Direct presence doesn't seem to work for non-roster elementsClosed2016-07-222016-08-05

Associated revisions

Revision 2c9294a4 (diff)
Added by Wojciech Kapcia TigaseTeam over 2 years ago

Add support for direct presence; #4288

Revision 3eb324a9 (diff)
Added by Wojciech Kapcia TigaseTeam over 2 years ago

remove debuging from console output; #4288

Revision 86f727dc (diff)
Added by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

Task #4288 - changes in synchronization in login and disconnect methods used in blocking mode

Revision 0da7d850 (diff)
Added by Bartosz Malkowski TigaseTeam over 2 years ago

Feature #4284: SCRAM-SHA-1-PLUS implementation

fix: errors found in Task #4288

Revision da78a375 (diff)
Added by Bartosz Malkowski TigaseTeam over 2 years ago

fix: locks in threads
improved synchronized login and logout

(related to Task #4288)

History

#1 Updated by Eric Dziewa over 2 years ago

  • Tracker changed from Bug to Task

#2 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Subject changed from Examples Needed to Examples for direct presence and handling incomming message
  • Due date set to 2016-07-01
  • Status changed from New to Feedback
  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Eric Dziewa wrote:

Need a Groovy example in the wiki for sending a directed presence, and receiving a message.

I've created new page with samples for JaXMPP with modular design and added required example A very simple client in Groovy (direct presence + presence listen)

I've also added method to send direct presence so latest JaXMPP is required.

#3 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

I had to remove following line

contact.getProperties().setUserProperty(SocketConnector.TLS_DISABLED_KEY, Boolean.TRUE);
[root@n1 ~]# groovy jaxmpp.groovy
Loging in...
Jul 12, 2016 2:15:24 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Jul 12, 2016 2:15:24 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Resolving SRV recrd of domain 'tigase.org'
Jul 12, 2016 2:15:24 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t6.tigase.org/192.95.36.80:5222
Jul 12, 2016 2:15:25 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector reconnect
INFO: See other host: t2.tigase.org
Jul 12, 2016 2:15:25 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t2.tigase.org/198.27.120.208:5222
Jul 12, 2016 2:15:27 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=4b02272[TLS_RSA_WITH_AES_256_CBC_SHA256: Socket[addr=t2.tigase.org/198.27.120.208,port=5222,localport=37953]]]
Jul 12, 2016 2:15:27 PM tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule enable
INFO: Enabling stream management
Jul 12, 2016 2:15:27 PM tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule processStreamManagementEnabled
INFO: Stream management is enabled. id=bad534a5-088d-4954-91be-7ad2894ff9f0; resume=true
Presence received:      etd@tigase.org/dt is now away (null)
Presence received:      daniel@tigase.org/Psi is now online (null)
Presence received:      andrzej.wojcik@tigase.org/MacBook Pro (Andrzej) is now online (null)
Presence received:      andrzej.wojcik@tigase.org/iPhone (Andrzej) is now away (null)
Sending direct presence
Caught: groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule.sentDirectPresence() is applicable for argument types: (tigase.jaxmpp.core.client.JID, tigase.jaxmpp.core.client.xmpp.stanzas.Presence$Show, java.lang.String, java.lang.Integer) values: [etd@tigase.im/dt, away, new status, 65]
groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule.sentDirectPresence() is applicable for argument types: (tigase.jaxmpp.core.client.JID, tigase.jaxmpp.core.client.xmpp.stanzas.Presence$Show, java.lang.String, java.lang.Integer) values: [etd@tigase.im/dt, away, new status, 65]
        at jaxmpp.run(jaxmpp.groovy:40)
[root@n1 ~]#

Is it possible I can I turn off the extra INFO output?

#4 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Due date changed from 2016-07-01 to 2016-07-22
  • Assignee changed from Wojciech Kapcia to Eric Dziewa
  • logging:

Following imports have to be added:

import java.util.logging.Level
import java.util.logging.Logger

And then following code will suppress all logger output (only direct writes to the console will remain):

Logger log = Logger.getLogger( "tigase.jaxmpp" );
log.setUseParentHandlers( false );
log.setLevel( Level.OFF );
  • TLS_DISABLED_KEY

If the server requires STARTTLS this will fail.

  • exception Caught: groovy.lang.MissingMethodException

Following exception

Caught: groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule.sentDirectPresence()

please make sure you are using latest jaxmpp binaries as this method was added recently. I've configure jaxmpp jenkins project to place nightly distribution packages to http://build.tigase.org/nightlies/dists/ .

#5 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

Using the file http://build.tigase.org/nightlies/dists/2016-07-13/jaxmpp-dist-3.2.0-SNAPSHOT.tar.gz

[root@n1 ~]# groovy jaxmpp.groovy  
Loging in...
Jul 13, 2016 3:23:18 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Jul 13, 2016 3:23:18 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Resolving SRV recrd of domain 'tigase.org'
Jul 13, 2016 3:23:18 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t6.tigase.org/192.95.36.80:5222
Exception in thread "Thread-3" java.lang.AbstractMethodError: tigase.jaxmpp.j2se.connectors.socket.XMPPDomBuilderHandler.endElement(Ljava/lang/StringBuilder;)V
        at tigase.xml.SimpleParser.parse(SimpleParser.java:278)
        at tigase.jaxmpp.j2se.connectors.socket.Worker.run(Worker.java:113)

#6 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

do you also have tigase-xml (and I think tigase-utils may also be required)?

#7 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

I did have tigase-xmltools.jar.

Just built and installed tigase-utils-3.5.0-SNAPSHOT.jar and getting the same error.

#8 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Are you using wildcards to include all binaries in your CLASSPATH? This will case conflicts as we also bundle "singlejar" binaries (in addition to all individual modules) - you can either list desired modules and dependencies in classpath or remove singlejar binaries:

$ rm *singlejar*

or you can use only jaxmpp-j2se-singlejar.jar binary (but it didn't include jaxmpp-chat module so only next nightly will be usable).

#9 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

I was just extracting the files to ~/.groovy/lib/ . I have since removed them.

Using only jaxmpp-j2se-singlejar.jar.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b619/single/jaxmpp-j2se-singlejar.jar jaxmpp.groovy
Loging in...
Jul 19, 2016 1:30:07 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Jul 19, 2016 1:30:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Resolving SRV recrd of domain 'tigase.org'
Jul 19, 2016 1:30:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t2.tigase.org/198.27.120.208:5222
Exception in thread "Thread-3" java.lang.AbstractMethodError: tigase.jaxmpp.j2se.connectors.socket.XMPPDomBuilderHandler.endElement(Ljava/lang/StringBuilder;)V
        at tigase.xml.SimpleParser.parse(SimpleParser.java:323)
        at tigase.jaxmpp.j2se.connectors.socket.Worker.run(Worker.java:113)

^C

Using all jars minus the singles jaxmpp-all-singlejar.jar, jaxmpp-gwt-singlejar.jar, and jaxmpp-j2se-singlejar.jar. This same error happens with and without tigase-utils-3.5.0-SNAPSHOT.jar.

[root@n1 ~]# groovy -cp "jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-filetransfer.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-vcard.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-socks5.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-jingle.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-all.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-muc.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-presence.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-j2se.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-pubsub.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/dnsjava.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-chat.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-gwt.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-android.aar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-deliveryreceipts.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-registration.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/jaxmpp-xep0136.jar:jaxmpp-dist-3.2.0-SNAPSHOT-b619/tigase-xmltools.jar:tigase-utils-3.5.0-SNAPSHOT.jar" jaxmpp.groovy
Caught: java.lang.NoClassDefFoundError: tigase/jaxmpp/core/client/xmpp/modules/InitializingModule
java.lang.NoClassDefFoundError: tigase/jaxmpp/core/client/xmpp/modules/InitializingModule
Caused by: java.lang.ClassNotFoundException: tigase.jaxmpp.core.client.xmpp.modules.InitializingModule
[root@n1 ~]# ls -l jaxmpp-dist-3.2.0-SNAPSHOT-b619
total 1516
-rw-r--r-- 1  108  116 307637 Dec  3  2015 dnsjava.jar
drwxr-xr-x 4 root root   4096 Jul 19 13:25 docs
-rw-rw-r-- 1  108  116  28022 Jul 18 19:39 jaxmpp-adhoc.jar
-rw-rw-r-- 1  108  116   1716 Jul 18 19:40 jaxmpp-all.jar
-rw-r--r-- 1  108  116   7679 Jul 18 19:41 jaxmpp-android.aar
-rw-rw-r-- 1  108  116  32661 Jul 18 19:38 jaxmpp-chat.jar
-rw-rw-r-- 1  108  116 460334 Jul 18 19:37 jaxmpp-core.jar
-rw-rw-r-- 1  108  116   5133 Jul 18 19:39 jaxmpp-deliveryreceipts.jar
-rw-rw-r-- 1  108  116  14516 Jul 18 19:39 jaxmpp-filetransfer.jar
-rw-r--r-- 1  108  116  93237 Jul 18 19:42 jaxmpp-gwt.jar
-rw-rw-r-- 1  108  116 195151 Jul 18 19:40 jaxmpp-j2se.jar
-rw-rw-r-- 1  108  116  27518 Jul 18 19:39 jaxmpp-jingle.jar
-rw-rw-r-- 1  108  116  69191 Jul 18 19:38 jaxmpp-muc.jar
-rw-rw-r-- 1  108  116  29780 Jul 18 19:38 jaxmpp-presence.jar
-rw-rw-r-- 1  108  116  47921 Jul 18 19:39 jaxmpp-pubsub.jar
-rw-rw-r-- 1  108  116  16406 Jul 18 19:38 jaxmpp-registration.jar
-rw-rw-r-- 1  108  116  39323 Jul 18 19:38 jaxmpp-roster.jar
-rw-rw-r-- 1  108  116  20952 Jul 18 19:39 jaxmpp-socks5.jar
-rw-rw-r-- 1  108  116  14695 Jul 18 19:40 jaxmpp-vcard.jar
-rw-rw-r-- 1  108  116  12012 Jul 18 19:40 jaxmpp-xep0013_flexible_offline.jar
-rw-rw-r-- 1  108  116  29070 Jul 18 19:40 jaxmpp-xep0136.jar
drwxr-xr-x 2 root root   4096 Jul 19 13:29 single
-rw-rw-r-- 1  108  116  50186 Feb 21  2015 tigase-xmltools.jar
[root@n1 ~]# ls -l jaxmpp-dist-3.2.0-SNAPSHOT-b619/single
total 2596
-rw-r--r-- 1 108 116  837483 Jul 18 19:42 jaxmpp-all-singlejar.jar
-rw-r--r-- 1 108 116  622624 Jul 18 19:42 jaxmpp-gwt-singlejar.jar
-rw-r--r-- 1 108 116 1191519 Jul 18 19:43 jaxmpp-j2se-singlejar.jar

#10 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Status changed from Feedback to Resolved
  • Assignee changed from Wojciech Kapcia to Eric Dziewa

OK, I was testing with local binaries hence it was working as I was building them individually.

Observations:

  • tigase-utils is not needed;

  • tigase-xmltools is already bundled in jaxmpp distribution packages (and it's needed when using individual modules!)

  • -singlejar binaries already contain everything, therefore only one/single -singlejar binary is needed (for desired platform);

  • jaxmpp-j2se-singlejar included explicit dependency set to tigase-xmltools (even though it has dependency on jaxmpp-core@, which also has same dependency!), however there was a version difference ( @-singlejar was using 3.4.5 stable version and this version was inclined in the singlejar binary, but it's incompatible with current -SNAPSHOT version of jaxmpp -- I've removed this dependency and afterwards jaxmpp-j2se-singlejar works as single dependency:

wojtek@atlantiscity.local ~/dev/tigase/jaxmpp3testing/src/main/groovy/se.unir/jaxmpp3testing $ cp jaxmpp-dist-3.2.0-SNAPSHOT-b620/jaxmpp-j2se-singlejar.jar ./jars
wojtek@atlantiscity.local ~/dev/tigase/jaxmpp3testing/src/main/groovy/se.unir/jaxmpp3testing $ ls -lah jars/
total 2336
drwxr-xr-x  3 wojtek  staff   102B Jul 20 12:10 .
drwxr-xr-x  6 wojtek  staff   204B Jul 20 12:01 ..
-rw-r--r--  1 wojtek  staff   1.1M Jul 20 12:10 jaxmpp-j2se-singlejar.jar
wojtek@atlantiscity.local ~/dev/tigase/jaxmpp3testing/src/main/groovy/se.unir/jaxmpp3testing $ groovy -cp "jars/*" JaxmppPresence.groovy
Loging in...
Presence received:  admin@atlantiscity/422068608-tigase-8 is now online (null)
Sending direct presence
Sending direct unavailable presence
Waiting for the presence for 10 minutes
  • jaxmpp-android also used same dependency as above, and because this is one of the dependencies of jaxmpp-dist directly influencing included binaries in distribution packages, it resulted in including older tigase-xmltools (individual jar binary, not inlined in other jar files) overriding jaxmpp-core dependency on the latest version of @tigase-xmltools@; I've removed this dependency, rebuilt the distribution binary and
wojtek@atlantiscity.local $ cp jaxmpp-dist-3.2.0-SNAPSHOT-b620/*jar ./jars
wojtek@atlantiscity.local $ rm jars/*singlejar*
wojtek@atlantiscity.local $ ls -lah jars/
total 3008
drwxr-xr-x  22 wojtek  staff   748B Jul 20 12:14 .
drwxr-xr-x   6 wojtek  staff   204B Jul 20 12:01 ..
-rw-r--r--   1 wojtek  staff   300K Jul 20 12:13 dnsjava.jar
-rw-r--r--   1 wojtek  staff    27K Jul 20 12:13 jaxmpp-adhoc.jar
-rw-r--r--   1 wojtek  staff   1.7K Jul 20 12:13 jaxmpp-all.jar
-rw-r--r--   1 wojtek  staff    32K Jul 20 12:13 jaxmpp-chat.jar
-rw-r--r--   1 wojtek  staff   450K Jul 20 12:13 jaxmpp-core.jar
-rw-r--r--   1 wojtek  staff   5.0K Jul 20 12:13 jaxmpp-deliveryreceipts.jar
-rw-r--r--   1 wojtek  staff    14K Jul 20 12:13 jaxmpp-filetransfer.jar
-rw-r--r--   1 wojtek  staff    91K Jul 20 12:13 jaxmpp-gwt.jar
-rw-r--r--   1 wojtek  staff   191K Jul 20 12:13 jaxmpp-j2se.jar
-rw-r--r--   1 wojtek  staff    27K Jul 20 12:13 jaxmpp-jingle.jar
-rw-r--r--   1 wojtek  staff    68K Jul 20 12:13 jaxmpp-muc.jar
-rw-r--r--   1 wojtek  staff    29K Jul 20 12:13 jaxmpp-presence.jar
-rw-r--r--   1 wojtek  staff    47K Jul 20 12:13 jaxmpp-pubsub.jar
-rw-r--r--   1 wojtek  staff    16K Jul 20 12:13 jaxmpp-registration.jar
-rw-r--r--   1 wojtek  staff    38K Jul 20 12:13 jaxmpp-roster.jar
-rw-r--r--   1 wojtek  staff    20K Jul 20 12:13 jaxmpp-socks5.jar
-rw-r--r--   1 wojtek  staff    14K Jul 20 12:13 jaxmpp-vcard.jar
-rw-r--r--   1 wojtek  staff    12K Jul 20 12:13 jaxmpp-xep0013_flexible_offline.jar
-rw-r--r--   1 wojtek  staff    28K Jul 20 12:13 jaxmpp-xep0136.jar
-rw-r--r--   1 wojtek  staff    53K Jul 20 12:13 tigase-xmltools.jar
wojtek@atlantiscity.local $ groovy -cp "jars/*" JaxmppPresence.groovy
Loging in...
Presence received:  admin@atlantiscity/422068608-tigase-9 is now online (null)
Sending direct presence
Sending direct unavailable presence
Waiting for the presence for 10 minutes

#11 Updated by Eric Dziewa over 2 years ago

  • Status changed from Resolved to Feedback
  • Assignee changed from Eric Dziewa to Wojciech Kapcia

That is working now.

A few things.

This only works for presences received from roster entries. I would like to avoid roster altogether. Just need to grab any presence sent to the client.

Can I have finer grained control. Similar to Tsung. ie. not sending initial presence until requested, Not requesting roster automatically.

Does the library have ability to specify machine to connect to? ie. login to tigase.im using machine blue.tigase.im.

In order for me to not to a major re-write this section of monitor code I'd like to keep this minimal.

One client logs in and does nothing but responds to messages, presences. Nothing more.

The other logs in sends/receives one each of presence/message to/from the first. Prints out 3 numbers and exits.

Trying to send a message using example from wiki.

contact.sendMessage(JID.jidInstance("etd@tigase.im"), "Test", "This is a test")
Caught: groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.j2se.Jaxmpp.sendMessage() is applicable for argument types: (tigase.jaxmpp.core.client.JID, java.lang.String, java.lang.String) values: [etd@tigase.im, Test, This is a test]
groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.j2se.Jaxmpp.sendMessage() is applicable for argument types: (tigase.jaxmpp.core.client.JID, java.lang.String, java.lang.String) values: [etd@tigase.im, Test, This is a test]
        at jaxmpp.run(jaxmpp.groovy:71)

Can you please write in the Wiki how to send/receive messages?

#12 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Eric Dziewa wrote:

That is working now.

A few things.

This only works for presences received from roster entries. I would like to avoid roster altogether. Just need to grab any presence sent to the client.

It shouldn't work that way and I just tested and direct presence works... somewhat - it doesn't seem to work for direct presence to barejid - I've created #4365 to investigate it further.

Can I have finer grained control. Similar to Tsung. ie. not sending initial presence until requested, Not requesting roster automatically.

You can remove line:

tigase.jaxmpp.j2se.Roster.initialize(contact);

Which will prevent library from requesting the roster from the server.

Does the library have ability to specify machine to connect to? ie. login to tigase.im using machine blue.tigase.im.

Yes it does, use following:

contact.getConnectionConfiguration().setServer("blue.tigase.im");

In order for me to not to a major re-write this section of monitor code I'd like to keep this minimal.

One client logs in and does nothing but responds to messages, presences. Nothing more.

The other logs in sends/receives one each of presence/message to/from the first. Prints out 3 numbers and exits.

Trying to send a message using example from wiki.

[...]

[...]

Can you please write in the Wiki how to send/receive messages?

I've added examples to Wiki section with examples for JaXMPP3 - https://projects.tigase.org/projects/jaxmpp2/wiki/Example_codes_(3x)

Change for sending a message is simple - you need to use particular module:

contact.getModule(MessageModule.class).sendMessage(JID.jidInstance("tigase1@atlantiscity"), "Test", "This is a test")

#13 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Related to Bug #4365: Direct presence doesn't seem to work for non-roster elements added

#14 Updated by Eric Dziewa over 2 years ago

  • Due date changed from 2016-07-22 to 2016-08-05
  • Assignee changed from Eric Dziewa to Wojciech Kapcia

You can remove line:

tigase.jaxmpp.j2se.Roster.initialize(contact);

Which will prevent library from requesting the roster from the server.

This did not work.

%andrzej.wojcik can you tell me how to get the full sender JID when receiving a message? Wojciech is away.

#15 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Method onMessageReceived of MessageModule.MessageReceivedHandler is called when you receive message and in this method there is passed parameter stanza of class Message@. This class contains method @getFrom() which will return instance of JID class with full jid of message sender.

contact.getEventBus().addHandler(MessageModule.MessageReceivedHandler.MessageReceivedEvent.class,
        new MessageModule.MessageReceivedHandler() {

            @Override
            public void onMessageReceived(SessionObject sessionObject, Chat chat, Message stanza) {
                System.out.println("message: " + stanza.getBody());
                System.out.println("from: " + stanza.getFrom());
            }
        });

#16 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

Need to send direct presence priority as a Long. The method takes an Integer now.

Caught: groovy.lang.MissingMethodException: No signature of method: tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule.sentDirectPresence() is applicable for argument types: (tigase.jaxmpp.core.client.JID, tigase.jaxmpp.core.client.xmpp.stanzas.Presence$Show, java.lang.String, java.lang.Long) values: [etd@tigase.org/dt, away, Groovy Baby, 1469540439095]
Possible solutions: sentDirectPresence(tigase.jaxmpp.core.client.JID, tigase.jaxmpp.core.client.xmpp.stanzas.Presence$Show, java.lang.String, java.lang.Integer)

#17 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

This is correct as according to RFC 6121 in section 4.7.2.3. Priority Element value must be between -128 up to +127:

The value MUST be an integer between -128 and +127.

Due to that I do not think we should change it to allow Long.

In fact we should not accept Long and even we should check value of integer to make sure it is in proper range.

Why do you "need" to pass Long as priority?

#18 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

You've pointed that out before. Tigase Server accepts the long value. I need to send timestamp in milliseconds.

#19 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

You can use status/description field for that which accepts String - just use

to covert Long to String and set as status and later use:

to parse status and get Long.

If you use sendDirectPresence(jid, show, null, timestamp) then change it to sendDirectPresence(jid, show, String.valueOf(timestamp), null) and it should work for sending timestamp as a status.

#20 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

That was a good idea Andrzej. Works.

What I'm trying to do now is test is login was successful. The line

contact.getProperties().setUserProperty(SocketConnector.TLS_DISABLED_KEY, Boolean.TRUE);

logs us into tigase.org but fails on cluster-c.xmpp-test.net.

Here's what I'm trying

    try { contact.login(true) } catch( Exception err) { println "exception" }
    println("etd")
    if (! contact.isConnected()) {
        contact.getProperties().setUserProperty(SocketConnector.TLS_DISABLED_KEY, Boolean.FALSE)
        println("not connected trying again.")
    }
    contact.login(true)

The program gives a warning when first contact.login(true) fails then doesn't continue processing any further code.

WARNING: 
tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$UnsupportedSaslMechanisms: Not found supported SASL mechanisms.
        at tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login(SaslModule.java:165)
        at tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login(AuthModule.java:203)
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic.processStreamFeatures(AbstractSocketXmppSessionLogic.java:213)
        at tigase.jaxmpp.j2se.connectors.socket.SocketXmppSessionLogic.processStreamFeatures(SocketXmppSessionLogic.java:64)
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2.onStreamFeaturesReceived(AbstractSocketXmppSessionLogic.java:72)
        at tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent.dispatch(StreamFeaturesModule.java:108)
        at tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent.dispatch(StreamFeaturesModule.java:97)
        at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:64)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

Do you have any suggestions?

#21 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

If TLS is set as required (ie. due to enabled hardened mode) then following line:

contact.getProperties().setUserProperty(SocketConnector.TLS_DISABLED_KEY, Boolean.TRUE);

should not be used as it disables TLS which is marked on server as required.

In default configuration TLS is now required if I'm correct so I would suggest to remove this line.

#22 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

Right if TLS_DISABLED_KEY is set to true, cannot login to server if --hardened-mode=true.

hardened-mode is not the default. I need a way to recover if TLS_DISABLED_KEY true fails.

Can we maybe have login throw an exception instead of a warning/stop so we can catch it and proceed? Previous library auto-negotiated that.

#23 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

If you remove TLS_DISABLED_KEY then Jaxmpp will negotiate use of TLS (will use it if available).

If you wish to force Jaxmpp to accept any certificate (which may be a good idea for this tool) then you can add following code:

            X509TrustManager trustManager = new X509TrustManager() {
                @Override
                public void checkClientTrusted(X509Certificate[] x509Certificates, String s) throws CertificateException {
                    System.out.println("checking client certifiate");
                }

                @Override
                public void checkServerTrusted(X509Certificate[] x509Certificates, String s) throws CertificateException {
                    System.out.println("checking server certifiate");
                }

                @Override
                public X509Certificate[] getAcceptedIssuers() {
                    return new X509Certificate[0];
                }
            };

            jaxmpp.getSessionObject().setUserProperty(SocketConnector.TRUST_MANAGERS_KEY, new X509TrustManager[] { trustManager });

where jaxmpp is instance of Jaxmpp.

We cannot throw exception in easy way as this entry about no possible SASL mechanism to use is generated on different thread than thread which called login() method.

%bmalkow What do you think? Would it be possible to add this exception in this case to login() method?

#24 Updated by Eric Dziewa over 2 years ago

Problem with that code.

org.codehaus.groovy.control.MultipleCompilationErrorsException: startup failed:
/root/jaxmpp.groovy: 48: No expression for the array constructor call at line: 48 column: 101. File: /root/jaxmpp.groovy @ line 48, column 101.
   GERS_KEY, new X509TrustManager[] { trust
                                                               ^

Changed the square brackets to parens.

 jaxmpp.getSessionObject().setUserProperty(SocketConnector.TRUST_MANAGERS_KEY, new X509TrustManager[] { trustManager });

 jaxmpp.getSessionObject().setUserProperty(SocketConnector.TRUST_MANAGERS_KEY, new X509TrustManager() { trustManager });
org.codehaus.groovy.control.MultipleCompilationErrorsException: startup failed:
/root/jaxmpp.groovy: 48: unexpected token: trustManager @ line 48, column 106.
   KEY, new X509TrustManager() { trustManag
                                                           ^

#25 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

I checked this in Java and in groovy it needs to be adjusted.

Try following code:

jaxmpp.getSessionObject().setUserProperty(SocketConnector.TRUST_MANAGERS_KEY, [trustManager] as X509TrustManager[]);

#26 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

Is that code included in jaxmpp-j2se-singlejar.jar ? Because I'm getting lots of unable to resolve class errors now all related to this.

#27 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

SocketConnector.TRUST_MANAGERS_KEY is from Jaxmpp-J2SE and is part of jaxmpp-j2se-singlejar.jar

X509TrustManager interface is part of Java Virtual Machine so it is always available.

I suppose you need to import X509TrustManager@, @X509Certificate and CertificateException ie. using following lines:

import javax.net.ssl.X509TrustManager;
import java.security.cert.CertificateException;
import java.security.cert.X509Certificate;

added to import section of your groovy file.

#28 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

Works great thanks Andrzej.

Is there a way to ignore see other host?

Jul 29, 2016 12:45:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t6.tigase.org/192.95.36.80:5222
Jul 29, 2016 12:45:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector reconnect
INFO: See other host: t2.tigase.org
Jul 29, 2016 12:45:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to t2.tigase.org/198.27.120.208:5222

#29 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

You can disable see-other-host feature on client side by adding following line:

jaxmpp.getSessionObject().setUserProperty(Connector.SEE_OTHER_HOST_KEY, false);

You may need to import Connector interface using following line:

import tigase.jaxmpp.core.client.Connector;

#30 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

%wojtek even with all logging turned off /stream:stream is printed to stdout when disconnecting. Can we get rid of that?

#31 Updated by Eric Dziewa over 2 years ago

Also is there something going on with jaxmpp object disconnect method? I'm trying to exit the program but it just hangs 9 times out of 10 and keeps using cpu and memory. Here's how I'm trying to exit.

                contact.disconnect()
                System.exit(0)

#32 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Andrzej Wójcik

Eric Dziewa wrote:

wojtek even with all logging turned off /stream:stream is printed to stdout when disconnecting. Can we get rid of that?

%eric

I've removed this debug from output.

Eric Dziewa wrote:

Also is there something going on with jaxmpp object disconnect method? I'm trying to exit the program but it just hangs 9 times out of 10 and keeps using cpu and memory. Here's how I'm trying to exit.

[...]

I've looked over the disconnect method and:

  • I think there is no way to disconnect in async manner when the login was done in synced way (which is the default, if no explicit async was used), vide tigase.jaxmpp.j2se.Jaxmpp#disconnect(boolean, boolean) and lines:
    public void disconnect(boolean snc, boolean resetStreamManagement) throws JaxmppException {
        try {
            if (this.connector != null) {
                Boolean sync = this.sessionObject.getProperty(SYNCHRONIZED_MODE);
                sync = snc || (sync != null && sync);
                Connector.DisconnectedHandler handler = null;
                if (sync) {

  • in the same method %andrzej.wojcik added in task #4076 a way to close the connection only after the library received the </stream:stream> response back from the server -- Andrzej, I'm not sure (haven't looked at all changes), but wouldn't it just hang indefinitely in case response was not received and/or this.eventBus.addHandler(Connector.DisconnectedHandler.DisconnectedEvent.class, handler); (v. jaxmpp-j2se/src/main/java/tigase/jaxmpp/j2se/Jaxmpp.java:123) would not fire? Maybe some kind of timeout should be used?

#33 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Wojciech Kapcia

%wojtek Jaxmpp when connection is closed fires DisconnectedEvent as follows on disconnection (or at least this is what I think should happen):

  • if /stream:stream was received

  • if TCP socket is closed

  • if we send /stream:stream and we did not received /stream:stream nor server did not close connection then timer is started with 3 seconds timeout (if I'm correct)

As for using sync disconnection if login was also sync, I think it was added to make it behave in same way as not so long ago disconnection was always sync (just closed TCP connection) which was not correct. But after call to this method Jaxmpp was always disconnected, so not it will behave the same if login was used with snc = true

#34 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Andrzej Wójcik

Andrzej Wójcik wrote:

  • if we send /stream:stream and we did not received /stream:stream nor server did not close connection then timer is started with 3 seconds timeout (if I'm correct)

Which timer? Haven't found it.

As for using sync disconnection if login was also sync, I think it was added to make it behave in same way as not so long ago disconnection was always sync (just closed TCP connection) which was not correct. But after call to this method Jaxmpp was always disconnected, so not it will behave the same if login was used with snc = true

OK, making it more consistent is good, but currently it's not possible to have async disconnect on synced connection while it's possible to have async login and override it and use sync disconnect. Sometimes we simply want to disconnect the user.

#35 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

Eric Dziewa wrote:

I'm trying to exit the program but it just hangs 9 times out of 10 and keeps using cpu and memory.

Eric, with such high ratio - can you re-run it with enabled full debug (Level.FINEST) and share the full log? Would help with identifying the issue.

Also - can you elaborate on "using CPU and memory"? Is the CPU and memory usage high?

#36 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

I change level to FINEST and nothing additional is output.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b621/single/jaxmpp-j2se-singlejar.jar jaxmpp.groovy process test_user_2 test_user_2 c01.xmpp-test.net cluster-c.xmpp-test.net test_user_1
1152 11 5 
</stream:stream>
^C[root@n1 ~]#

I remove all logging code you provided in post #4 which should restore output as I pasted in post #3 but it doesn't.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b621/single/jaxmpp-j2se-singlejar.jar jaxmpp.groovy process test_user_2 test_user_2 c01.xmpp-test.net cluster-c.xmpp-test.net test_user_1
Aug 04, 2016 1:03:58 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 04, 2016 1:03:59 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 04, 2016 1:03:59 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=5f8af5d5[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=48369]]]
990 16 4 
</stream:stream>
^C

Initially after /stream:stream cpu is high above 10. Gradually goes down to 0.1, looks like that takes about 30 minutes. Memory is constant 0.9.

#37 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Due date changed from 2016-08-05 to 2016-08-12
  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Eric Dziewa wrote:

I change level to FINEST and nothing additional is output.

Please share complete code so it will be easier to figure out what's wrong.

I remove all logging code you provided in post #4 which should restore output as I pasted in post #3 but it doesn't.

To get the verbose logging please use following imports:

import java.util.logging.Handler
import java.util.logging.ConsoleHandler
import java.util.logging.Level
import java.util.logging.Logger

and this logger configuration:

Logger log = Logger.getLogger( "tigase.jaxmpp" );

Handler handler = new ConsoleHandler();

handler.setLevel( Level.ALL );

log.setUseParentHandlers( false );
log.addHandler( handler );
log.setLevel( Level.ALL );

Initially after /stream:stream cpu is high above 10. Gradually goes down to 0.1, looks like that takes about 30 minutes. Memory is constant 0.9.

OK, just an observation - you've received /stream:stream from the server, which should trigger the event in library.

Can you try disabling Stream Management?

contact.getProperties().setUserProperty(StreamManagementModule.STREAM_MANAGEMENT_DISABLED_KEY, Boolean.TRUE);

#38 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

Here is what I'm doing.

                printf("\n\n%s\n\n", printMe)
                contact.disconnect()
                System.exit(0)

This is a successful termination.

970 24 11 

Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: connected->disconnecting
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=405215542): </stream:stream>
</stream:stream>
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: disconnecting->disconnected
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 05, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)

Unsuccessful.

1080 17 10 

Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: connected->disconnecting
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=405215542): </stream:stream>
</stream:stream>
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: disconnecting->disconnected
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
WARNING: 
java.lang.IllegalStateException: Timer already cancelled.
        at java.util.Timer.sched(Timer.java:397)
        at java.util.Timer.schedule(Timer.java:193)
        at tigase.jaxmpp.j2se.connectors.socket.SocketConnector.terminateAllWorkers(SocketConnector.java:843)
        at tigase.jaxmpp.j2se.connectors.socket.SocketConnector.stop(SocketConnector.java:807)
        at tigase.jaxmpp.core.client.connector.ConnectorWrapper.stop(ConnectorWrapper.java:99)
        at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:130)
        at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:109)
        at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:105)
        at tigase.jaxmpp.j2se.Jaxmpp$disconnect$1.call(Unknown Source)
        at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:48)
        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:113)
        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:117)
        at jaxmpp$4.onMessageReceived(jaxmpp.groovy:184)
        at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:409)
        at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:395)
        at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:64)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 999468314Disconnecting: state=disconnected; buffer=-1   Thread[Thread-3,5,main]
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$10 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
Aug 05, 2016 1:27:49 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent

I have seen that error Timer already cancelled before, it is very rare. Most of the hangs look like this.

1013 25 9 

Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: connected->disconnecting
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=405215542): </stream:stream>
</stream:stream>
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: disconnecting->disconnected
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1615936730Disconnecting: state=disconnected; buffer=-1   Thread[Thread-3,5,main]
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 05, 2016 2:08:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated

Disabling stream management with the line you provided. Hangs here most of the time.

1051 30 14 

Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: connected->disconnecting
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=405215542): </stream:stream>
</stream:stream>
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: disconnecting->disconnected
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1615936730Disconnecting: state=disconnected; buffer=-1   Thread[Thread-3,5,main]
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 05, 2016 1:59:07 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated

A successful termination.

1048 18 36 

Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: connected->disconnecting
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=405215542): </stream:stream>
</stream:stream>
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=405215542) state changed: disconnecting->disconnected
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=405215542)
Aug 05, 2016 1:59:45 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!

#39 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Eric, I've added small additional debug - can you check the next nightly?

%andrzej.wojcik - could you check the close timer, if it's not interrupting the disconnection with the silent exceptions?

#40 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

%wojtek There was an issue reported that Jaxmpp was not changing state to disconnected but disconnected() method was not blocking processing.

Looking at logs I think that there is no timer being used (time delays close for up to 3 seconds, and there is no such delay in connection close in logs).

I think it is possible race condition in Jaxmpp class in disconnect() method. In this method connector.stop() is called before synchronized block in which Jaxmpp.this.wait() is called. In theory it could be possible that DisconnectedEvent is fired before Jaxmpp.this.wait() is called as both things are executed on separate threads in 3.2 (in 3.1 events were called on same thread as they were fired, in 3.2 there is separate thread for that). This would lead to infinite wait as notify() will never be called. I think we could try to move this.connector.stop() into synchronized block.

#41 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

%andrzej.wojcik

OK, but that would only apply to the "synchronised mode" (*) - and in such we should make this call in synchronised block - we could have one logic for synchronised mode and then in non-sychronised mode simply call connector.stop()

Eric, can you try using jaxmpp.login(false); add Thread.sleep(5000); after it and then use jaxmpp.disconnect(false); at the end; and then check if it still hangs?

(*) - which, as discussed previously is a bit weird, especially with this code:

    @Override
    public void disconnect() throws JaxmppException {
        disconnect(false);
    }

    public void disconnect(boolean snc) throws JaxmppException {
        disconnect(snc, true);
    }

    public void disconnect(boolean snc, boolean resetStreamManagement) throws JaxmppException {
        try {
            if (this.connector != null) {

#42 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

Keep getting not authorized exception with latest nightly.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b628/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=2114289475).
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: null->connecting
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connecting->connected
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Starting LoginTimeoutTask
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1345067402 Starting Thread[Thread-3,5,main]
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=2114289475)
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=531c8ed2[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=35397]]]
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 116294596093022142941876702753079388891532392139408611149628258330612302114234699098924601737639062484715020127458341559843556819052194004588505690188315277610093151051089449364856931189567403570987984698629379472102024789245157553945611000903849194093552792202256341420014433156951167042706051986346482051051
  public exponent: 65537
  Validity: [From: Fri Jul 29 21:00:43 EDT 2016,
               To: Sat Jul 29 21:00:43 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    579bfc3b]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 25 E5 1E AF 87 0F BB 9C   0A 29 E8 D1 0D FB 37 84  %........)....7.
0010: B4 B0 B5 30 46 71 D4 79   89 CA C6 A8 6F 2F B8 03  ...0Fq.y....o/..
0020: 52 7F B8 FE 7D 85 DC B1   A7 98 B1 D0 70 24 35 E9  R...........p$5.
0030: DE 1F 1A 7F 7D 9D A8 33   E4 0D 6E 33 F5 01 56 8F  .......3..n3..V.
0040: 2F A3 C8 D0 5E E6 4F 66   CC A5 AA 34 55 6D 58 40  /...^.Of...4UmX@
0050: 84 60 CA 9A 5F 48 2D 3B   BB 5D 2A ED DF 51 01 2F  .`.._H-;.]*..Q./
0060: 8A 43 A1 21 70 95 B7 00   84 89 FE DB 52 6E 79 F7  .C.!p.......Rny.
0070: CB 53 D2 4D FB 66 8D CC   55 FA 42 50 7C 2A 7F F3  .S.M.f..U.BP.*..

]]
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9R3VGVmEyVDBCZ0wzeVc2dzBTZ3Y=</auth>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9R3VGVmEyVDBCZ0wzeVc2dzBTZ3Y=</auth>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HdUZWYTJUMEJnTDN5VzZ3MFNndnYyU0REdE5va3BRa2ZUUVBNczAzLHM9TDU1M1VrbU5PVjdsbGc9PSxpPTQwOTY=</challenge>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBQjg1NzcrOVlPKy92VkIySGUrL3ZVZ0ZJUjN2djczdnY3M3Z2NzN2djczdnY3M3Z2NzAyNzcrOVpBdnZ2NzN2djcxdGN1Ky92ZSsvdlE9PSxyPUd1RlZhMlQwQmdMM3lXNncwU2d2djJTRER0Tm9rcFFrZlRRUE1zMDMscD1NTk50M0Q3VUZ2M3hISGs1TDVDcjlJMVFKMWc9</response>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBQjg1NzcrOVlPKy92VkIySGUrL3ZVZ0ZJUjN2djczdnY3M3Z2NzN2djczdnY3M3Z2NzAyNzcrOVpBdnZ2NzN2djcxdGN1Ky92ZSsvdlE9PSxyPUd1RlZhMlQwQmdMM3lXNncwU2d2djJTRER0Tm9rcFFrZlRRUE1zMDMscD1NTk50M0Q3VUZ2M3hISGs1TDVDcjlJMVFKMWc9</response>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Channel bindings dont match</text></failure>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processFailure
FINE: Failure with condition: not_authorized
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthFailedHandler$SaslAuthFailedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.Jaxmpp onException
FINE: Catching exception
tigase.jaxmpp.core.client.exceptions.JaxmppException: Unauthorized with condition=not_authorized
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic.processAuthFailed(AbstractSocketXmppSessionLogic.java:160)
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$3.onAuthFailed(AbstractSocketXmppSessionLogic.java:81)
        at tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent.dispatch(AuthModule.java:260)
        at tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent.dispatch(AuthModule.java:249)
        at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:64)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connected->disconnecting
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): </stream:stream>
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: disconnecting->disconnected
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1345067402 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$10 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
^C

But sometimes not.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b628/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=2114289475).
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: null->connecting
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connecting->connected
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Starting LoginTimeoutTask
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1345067402 Starting Thread[Thread-3,5,main]
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=2114289475)
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=510a671[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=35425]]]
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 116294596093022142941876702753079388891532392139408611149628258330612302114234699098924601737639062484715020127458341559843556819052194004588505690188315277610093151051089449364856931189567403570987984698629379472102024789245157553945611000903849194093552792202256341420014433156951167042706051986346482051051
  public exponent: 65537
  Validity: [From: Fri Jul 29 21:00:43 EDT 2016,
               To: Sat Jul 29 21:00:43 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    579bfc3b]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 25 E5 1E AF 87 0F BB 9C   0A 29 E8 D1 0D FB 37 84  %........)....7.
0010: B4 B0 B5 30 46 71 D4 79   89 CA C6 A8 6F 2F B8 03  ...0Fq.y....o/..
0020: 52 7F B8 FE 7D 85 DC B1   A7 98 B1 D0 70 24 35 E9  R...........p$5.
0030: DE 1F 1A 7F 7D 9D A8 33   E4 0D 6E 33 F5 01 56 8F  .......3..n3..V.
0040: 2F A3 C8 D0 5E E6 4F 66   CC A5 AA 34 55 6D 58 40  /...^.Of...4UmX@
0050: 84 60 CA 9A 5F 48 2D 3B   BB 5D 2A ED DF 51 01 2F  .`.._H-;.]*..Q./
0060: 8A 43 A1 21 70 95 B7 00   84 89 FE DB 52 6E 79 F7  .C.!p.......Rny.
0070: CB 53 D2 4D FB 66 8D CC   55 FA 42 50 7C 2A 7F F3  .S.M.f..U.BP.*..

]]
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9Wm1BaWlGUUkwb1F6blBUUFFsWnY=</auth>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9Wm1BaWlGUUkwb1F6blBUUFFsWnY=</auth>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1abUFpaUZRSTBvUXpuUFRQUWxadm9qcUNBZk5lOFFXaEhyVndQUk1ZLHM9ZjRSUXBzYVAyNkwxVEE9PSxpPTQwOTY=</challenge>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBSHJ2djczdnY3MWNSdSsvdlRydnY3MWs3Nys5UnUrL3ZSL3Z2NzN2djczdnY3M3Z2NzBKV216dnY3MHE3Nys5TzB4R2ZBZzIscj1abUFpaUZRSTBvUXpuUFRQUWxadm9qcUNBZk5lOFFXaEhyVndQUk1ZLHA9T3ZscGlFU3dCUjViUWhwWVdLVHRFZW9vQTFNPQ==</response>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBSHJ2djczdnY3MWNSdSsvdlRydnY3MWs3Nys5UnUrL3ZSL3Z2NzN2djczdnY3M3Z2NzBKV216dnY3MHE3Nys5TzB4R2ZBZzIscj1abUFpaUZRSTBvUXpuUFRQUWxadm9qcUNBZk5lOFFXaEhyVndQUk1ZLHA9T3ZscGlFU3dCUjViUWhwWVdLVHRFZW9vQTFNPQ==</response>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1MbE9PVUxLUi9xaU0zWUhVQ2x6c1grMEFlTWc9</success>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processSuccess
FINE: Authenticated
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthSuccessHandler$SaslAuthSuccessEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$4 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthSuccessHandler$AuthSuccessEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></features>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="9iP0q7" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="9iP0q7" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq id="9iP0q7" xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>test_user_1@cluster-c.xmpp-test.net/bread</jid></bind></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Waked up
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="kbs6jU" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="kbs6jU" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq id="kbs6jU" xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" type="result"/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$7 with event class tigase.jaxmpp.core.client.xmpp.modules.SessionEstablishmentModule$SessionEstablishmentSuccessHandler$SessionEstablishmentSuccessEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq to="cluster-c.xmpp-test.net" id="3vo9oR" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq to="cluster-c.xmpp-test.net" id="3vo9oR" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq id="3vo9oR" xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" type="result" from="cluster-c.xmpp-test.net"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="im" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)"/><identity category="server" type="im" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule with event class tigase.jaxmpp.core.client.XmppSessionLogic$XmppSessionEstablishedHandler$XmppSessionEstablishedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <presence/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$BeforePresenceSendHandler$BeforePresenceSendEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINEST: Presence received from test_user_1@cluster-c.xmpp-test.net/bread :: <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINER: Presence online from test_user_1@cluster-c.xmpp-test.net/bread
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactChangedPresenceHandler$ContactChangedPresenceEvent
Aug 09, 2016 12:10:34 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactAvailableHandler$ContactAvailableEvent
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Canceling LoginTimeoutTask
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1470759035499</status></presence>
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1470759035499</status></presence>
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <message id="Ut6t5D" xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" from="tigasemonitor@cluster-c.xmpp-test.net/butter"><subject/><body>22</body></message>
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigaseJaXMPPclients$4 with event class tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="pal0j2"><subject></subject><body>1470759037528</body></message>
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="pal0j2"><subject></subject><body>1470759037528</body></message>
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <message id="Ju7yxm" xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" from="tigasemonitor@cluster-c.xmpp-test.net/butter"><subject/><body>5</body></message>
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigaseJaXMPPclients$4 with event class tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent


1317 22 5 

Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connected->disconnecting
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): </stream:stream>
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: disconnecting->disconnected
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1345067402 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 09, 2016 12:10:37 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
^C

Out of 10 attempts 7 failed.

Adding this.

                printf("\n\n%s\n\n", printMe)
//              contact.disconnect()
                jaxmpp.login(false)
                Thread.sleep(5000)
                jaxmpp.disconnect(false)
               System.exit(0)
[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b628/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=2114289475).
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: null->connecting
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connecting->connected
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Starting LoginTimeoutTask
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1760467306 Starting Thread[Thread-3,5,main]
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=2114289475)
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=83c7abd[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=35574]]]
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 116294596093022142941876702753079388891532392139408611149628258330612302114234699098924601737639062484715020127458341559843556819052194004588505690188315277610093151051089449364856931189567403570987984698629379472102024789245157553945611000903849194093552792202256341420014433156951167042706051986346482051051
  public exponent: 65537
  Validity: [From: Fri Jul 29 21:00:43 EDT 2016,
               To: Sat Jul 29 21:00:43 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    579bfc3b]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 25 E5 1E AF 87 0F BB 9C   0A 29 E8 D1 0D FB 37 84  %........)....7.
0010: B4 B0 B5 30 46 71 D4 79   89 CA C6 A8 6F 2F B8 03  ...0Fq.y....o/..
0020: 52 7F B8 FE 7D 85 DC B1   A7 98 B1 D0 70 24 35 E9  R...........p$5.
0030: DE 1F 1A 7F 7D 9D A8 33   E4 0D 6E 33 F5 01 56 8F  .......3..n3..V.
0040: 2F A3 C8 D0 5E E6 4F 66   CC A5 AA 34 55 6D 58 40  /...^.Of...4UmX@
0050: 84 60 CA 9A 5F 48 2D 3B   BB 5D 2A ED DF 51 01 2F  .`.._H-;.]*..Q./
0060: 8A 43 A1 21 70 95 B7 00   84 89 FE DB 52 6E 79 F7  .C.!p.......Rny.
0070: CB 53 D2 4D FB 66 8D CC   55 FA 42 50 7C 2A 7F F3  .S.M.f..U.BP.*..

]]
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9TVNGd2IwT3ZXRmNqcWw2U01Vc2k=</auth>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9TVNGd2IwT3ZXRmNqcWw2U01Vc2k=</auth>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1NU0Z3YjBPdldGY2pxbDZTTVVzaXMzWk5pWjhDdzBGazVWNFJkc2xjLHM9OFJOSTVqTXh1TWtpZXc9PSxpPTQwOTY=</challenge>
Aug 09, 2016 12:16:43 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBZSsvdlF6dnY3MStOd29GWWsvdnY3M3Z2NzFjSVNnQzc3KzlDZSsvdmUrL3ZXME9URUFRTk8rL3ZlKy92ZSsvdlh3PSxyPU1TRndiME92V0ZjanFsNlNNVXNpczNaTmlaOEN3MEZrNVY0UmRzbGMscD02R29MZFl0c2l0bVBQWUdYRnpDRWdBTmpBUG89</response>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlBZSsvdlF6dnY3MStOd29GWWsvdnY3M3Z2NzFjSVNnQzc3KzlDZSsvdmUrL3ZXME9URUFRTk8rL3ZlKy92ZSsvdlh3PSxyPU1TRndiME92V0ZjanFsNlNNVXNpczNaTmlaOEN3MEZrNVY0UmRzbGMscD02R29MZFl0c2l0bVBQWUdYRnpDRWdBTmpBUG89</response>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1DVWZYVXoxMVk2MnZOQnNjNkdOdjJYWVdpMG89</success>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processSuccess
FINE: Authenticated
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthSuccessHandler$SaslAuthSuccessEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$4 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthSuccessHandler$AuthSuccessEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></features>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="Txyj6E" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="Txyj6E" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="Txyj6E"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>test_user_1@cluster-c.xmpp-test.net/bread</jid></bind></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Waked up
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="TyzlaM" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="TyzlaM" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="TyzlaM"/>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$7 with event class tigase.jaxmpp.core.client.xmpp.modules.SessionEstablishmentModule$SessionEstablishmentSuccessHandler$SessionEstablishmentSuccessEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq to="cluster-c.xmpp-test.net" id="Nez8EB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq to="cluster-c.xmpp-test.net" id="Nez8EB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule with event class tigase.jaxmpp.core.client.XmppSessionLogic$XmppSessionEstablishedHandler$XmppSessionEstablishedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="Nez8EB" from="cluster-c.xmpp-test.net"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)" type="im"/><identity category="server" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence/>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <presence/>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$BeforePresenceSendHandler$BeforePresenceSendEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINEST: Presence received from test_user_1@cluster-c.xmpp-test.net/bread :: <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINER: Presence online from test_user_1@cluster-c.xmpp-test.net/bread
Aug 09, 2016 12:16:44 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class

#43 Updated by Eric Dziewa over 2 years ago

Seems I was supposed to rename jaxmpp object.

                contact.login(false)
                Thread.sleep(5000)
                contact.disconnect(false)
[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b628/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=2114289475).
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: null->connecting
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connecting->connected
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Starting LoginTimeoutTask
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1760467306 Starting Thread[Thread-3,5,main]
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=2114289475)
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=83c7abd[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=35874]]]
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 116294596093022142941876702753079388891532392139408611149628258330612302114234699098924601737639062484715020127458341559843556819052194004588505690188315277610093151051089449364856931189567403570987984698629379472102024789245157553945611000903849194093552792202256341420014433156951167042706051986346482051051
  public exponent: 65537
  Validity: [From: Fri Jul 29 21:00:43 EDT 2016,
               To: Sat Jul 29 21:00:43 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    579bfc3b]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 25 E5 1E AF 87 0F BB 9C   0A 29 E8 D1 0D FB 37 84  %........)....7.
0010: B4 B0 B5 30 46 71 D4 79   89 CA C6 A8 6F 2F B8 03  ...0Fq.y....o/..
0020: 52 7F B8 FE 7D 85 DC B1   A7 98 B1 D0 70 24 35 E9  R...........p$5.
0030: DE 1F 1A 7F 7D 9D A8 33   E4 0D 6E 33 F5 01 56 8F  .......3..n3..V.
0040: 2F A3 C8 D0 5E E6 4F 66   CC A5 AA 34 55 6D 58 40  /...^.Of...4UmX@
0050: 84 60 CA 9A 5F 48 2D 3B   BB 5D 2A ED DF 51 01 2F  .`.._H-;.]*..Q./
0060: 8A 43 A1 21 70 95 B7 00   84 89 FE DB 52 6E 79 F7  .C.!p.......Rny.
0070: CB 53 D2 4D FB 66 8D CC   55 FA 42 50 7C 2A 7F F3  .S.M.f..U.BP.*..

]]
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9b2ZPajZMekN6YVNKQWRQRGIzdlE=</auth>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9b2ZPajZMekN6YVNKQWRQRGIzdlE=</auth>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1vZk9qNkx6Q3phU0pBZFBEYjN2UTdQZWlUUDhYdkxvRVN0elYwUFVoLHM9RGI0aW02YjA0S3dtZ3c9PSxpPTQwOTY=</challenge>
Aug 09, 2016 12:29:35 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlCTysvdlc4NUNIL3Z2NzF4NzcrOTc3KzlJanAyQSsrL3ZSTmk3Nys5NzcrOUJsb1Q3Nys5QWUrL3ZUYnZ2NzN2djczdnY3M3Z2NzA9LHI9b2ZPajZMekN6YVNKQWRQRGIzdlE3UGVpVFA4WHZMb0VTdHpWMFBVaCxwPUg1d3kxdndwVnkwOXlqemFxcG1BdWtuSUY4RT0=</response>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlCTysvdlc4NUNIL3Z2NzF4NzcrOTc3KzlJanAyQSsrL3ZSTmk3Nys5NzcrOUJsb1Q3Nys5QWUrL3ZUYnZ2NzN2djczdnY3M3Z2NzA9LHI9b2ZPajZMekN6YVNKQWRQRGIzdlE3UGVpVFA4WHZMb0VTdHpWMFBVaCxwPUg1d3kxdndwVnkwOXlqemFxcG1BdWtuSUY4RT0=</response>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1MZUNMME13M0Jhc1lHNmQxN2Q4TlBWNWF2NU09</success>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processSuccess
FINE: Authenticated
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthSuccessHandler$SaslAuthSuccessEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$4 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthSuccessHandler$AuthSuccessEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></features>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="EgJQZj" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="EgJQZj" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="EgJQZj"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>test_user_1@cluster-c.xmpp-test.net/bread</jid></bind></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Waked up
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="mqbJIM" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq xmlns="jabber:client" id="mqbJIM" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="mqbJIM"/>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$7 with event class tigase.jaxmpp.core.client.xmpp.modules.SessionEstablishmentModule$SessionEstablishmentSuccessHandler$SessionEstablishmentSuccessEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq to="cluster-c.xmpp-test.net" id="7hIEGF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <iq to="cluster-c.xmpp-test.net" id="7hIEGF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <iq xmlns="jabber:client" type="result" to="test_user_1@cluster-c.xmpp-test.net/bread" id="7hIEGF" from="cluster-c.xmpp-test.net"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)" type="im"/><identity category="server" name="Tigase ver. 7.1.0-SNAPSHOT-b4276/d063c075 (2016-07-29/23:12:41)" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule with event class tigase.jaxmpp.core.client.XmppSessionLogic$XmppSessionEstablishedHandler$XmppSessionEstablishedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence/>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <presence/>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$BeforePresenceSendHandler$BeforePresenceSendEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINEST: Presence received from test_user_1@cluster-c.xmpp-test.net/bread :: <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINER: Presence online from test_user_1@cluster-c.xmpp-test.net/bread
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactChangedPresenceHandler$ContactChangedPresenceEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactAvailableHandler$ContactAvailableEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.Jaxmpp login
FINEST: Canceling LoginTimeoutTask
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1470760176644</status></presence>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1470760176644</status></presence>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <message xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" id="pz8phz" from="tigasemonitor@cluster-c.xmpp-test.net/butter"><subject/><body>14</body></message>
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigaseJaXMPPclients$4 with event class tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="Kravm2"><subject></subject><body>1470760178660</body></message>
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="Kravm2"><subject></subject><body>1470760178660</body></message>
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <message xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" id="1HukXi" from="tigasemonitor@cluster-c.xmpp-test.net/butter"><subject/><body>5</body></message>
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigaseJaXMPPclients$4 with event class tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent


1223 14 5 

Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.Jaxmpp login
INFO: Cannot login, because Connector.State is connected
Aug 09, 2016 12:29:38 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
WARNING: 
tigase.jaxmpp.core.client.exceptions.JaxmppException: Connector is not in disconnected state
        at tigase.jaxmpp.j2se.Jaxmpp.login(Jaxmpp.java:291)
        at tigase.jaxmpp.j2se.Jaxmpp$login$0.call(Unknown Source)
        at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:48)
        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:113)
        at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:125)
        at tigaseJaXMPPclients$4.onMessageReceived(tigaseJaXMPPclients.groovy:173)
        at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:409)
        at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:395)
        at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:64)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

^C[root@n1 ~]# 

#44 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

%bmalkow Please look at this issue as I see following entries:

Aug 09, 2016 12:09:03 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=2114289475): <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Channel bindings dont match</text></failure>

which suggests some issues with SCRAM-SHA1-PLUS authentication mechanism.

#45 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Eric Dziewa

Eric, please share the exact script that you are using so we could try to replicate the problem, or an environment where you are executing the script (AFAIR you are working mostly on our servers). Ping-ponging suggestions and logs starts to feel a bit counter-productive.

#46 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

Eric, please also try next nightly as it includes latest fixes to the synchronisation, which should potentially fix the issue.

#47 Avatar?id=6098&size=24x24 Updated by Bartosz Malkowski TigaseTeam over 2 years ago

I added more validations to tigase-server (branch release). If it was what I thought then problem with channel binding is fixed :)

#48 Updated by Eric Dziewa over 2 years ago

Here is how you can test it yourself on n1.xmpp-test.net. I might have thought you knew because I've been pasting the command along with output.


I will check with the next nightly.

#49 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Wojciech Kapcia

Using b630.

groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b630/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor

Experiencing same problems unauthorized exceptions, and hangs.

A normal hang.

1098 11 8 

Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connected->disconnecting
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): </stream:stream>
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: disconnecting->disconnected
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1009504632 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 11, 2016 11:54:46 AM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
^C

Here is a successful termination. This contains 2 additional lines about clearing properties.

1176 23 5 

Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: connected->disconnecting
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=2114289475): </stream:stream>
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=2114289475) state changed: disconnecting->disconnected
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=2114289475)
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1009504632 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 11, 2016 12:00:19 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
[root@n1 ~]#

#50 Updated by Wojciech Kapcia TigaseTeam over 2 years ago

  • Assignee changed from Wojciech Kapcia to Andrzej Wójcik

%eric , with this build you can use:

            else if ( i == 2 ) {
                FH.append("got message response " + stanza.getBody() + " exiting\n" )
                printf("\n\n%s\n\n", printMe)
                contact.disconnect(false)
                Thread.sleep(2* 1000)
                System.exit(0)
            }

%andrzej.wojcik and %bmalkow - I'm not that familiar with the library but I've included more debug on that machine, and I think there are two problems:

  • if the connector is in disconnecting state - it uses Jaxmpp.this.wait(); but in that case eventbus onDisconnected() doesn't seem to be triggered - is eventbus tied to jaxmpp object?
10  1470993833511   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Using synchronous disconnect

10  1470993833511   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Stopping connector: tigase.jaxmpp.core.client.connector.ConnectorWrapper@5f98e400

10  1470993833511   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    setStage    Connector (oid=2144665602) state changed: connected->disconnecting

10  1470993833511   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateStream Terminating XMPP Stream

10  1470993833517   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    send    Send (oid=2144665602): </stream:stream>

10  1470993833517   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateAllWorkers Terminating all workers (oid=2144665602)

10  1470993833518   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Connector state: disconnecting

12  1470993833519   tigase.jaxmpp.j2se.connectors.socket.Worker$1   xmppStreamClosed    xmppStreamClosed()

12  1470993833519   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    setStage    Connector (oid=2144665602) state changed: disconnecting->disconnected

12  1470993833519   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    onStreamTerminate   Stream terminated

12  1470993833519   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateAllWorkers Terminating all workers (oid=2144665602)

12  1470993833521   tigase.jaxmpp.j2se.connectors.socket.Worker run 1935634429 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]

12  1470993833521   tigase.jaxmpp.j2se.connectors.socket.Worker interrupt   Worker Interrupted

12  1470993833521   tigase.jaxmpp.j2se.connectors.socket.Worker run Worker2 is interrupted

12  1470993833521   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    workerTerminated    Worker terminated

Full thread dump OpenJDK 64-Bit Server VM (25.51-b03 mixed mode):

"DestroyJavaVM" #24 prio=5 os_prio=0 tid=0x00007fed50009000 nid=0x4329 waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"pool-1-thread-1" #10 prio=5 os_prio=0 tid=0x00007fed5071b800 nid=0x4335 in Object.wait() [0x00007fed3a609000]

java.lang.Thread.State: WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x000000076d624a18> (a tigase.jaxmpp.j2se.Jaxmpp)

at java.lang.Object.wait(Object.java:502)

at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:153)

- locked <0x000000076d624a18> (a tigase.jaxmpp.j2se.Jaxmpp)

at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:112)

at tigase.jaxmpp.j2se.Jaxmpp.disconnect(Jaxmpp.java:108)

at tigase.jaxmpp.j2se.Jaxmpp$disconnect$1.call(Unknown Source)

at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:48)

at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:113)

at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:117)

at tigaseJaXMPPclients$5.onMessageReceived(tigaseJaXMPPclients.groovy:181)

at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:409)

at tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent.dispatch(MessageModule.java:395)

at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:64)

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)

at java.lang.Thread.run(Thread.java:745)

"Timer-0" #9 daemon prio=5 os_prio=0 tid=0x00007fed50718800 nid=0x4334 in Object.wait() [0x00007fed3a70b000]

java.lang.Thread.State: TIMED_WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x000000076e83e758> (a java.util.TaskQueue)

at java.util.TimerThread.mainLoop(Timer.java:552)

- locked <0x000000076e83e758> (a java.util.TaskQueue)

at java.util.TimerThread.run(Timer.java:505)

"Service Thread" #7 daemon prio=9 os_prio=0 tid=0x00007fed500ca800 nid=0x4332 runnable [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"C1 CompilerThread1" #6 daemon prio=9 os_prio=0 tid=0x00007fed500c8000 nid=0x4331 waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" #5 daemon prio=9 os_prio=0 tid=0x00007fed500ba800 nid=0x4330 waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" #4 daemon prio=9 os_prio=0 tid=0x00007fed500b8800 nid=0x432f waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00007fed50087800 nid=0x432e in Object.wait() [0x00007fed3b4b1000]

java.lang.Thread.State: WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x0000000771028780> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)

- locked <0x0000000771028780> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)

"Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x00007fed50085800 nid=0x432d in Object.wait() [0x00007fed3b5b2000]

java.lang.Thread.State: WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x0000000771028938> (a java.lang.ref.Reference$Lock)

at java.lang.Object.wait(Object.java:502)

at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:157)

- locked <0x0000000771028938> (a java.lang.ref.Reference$Lock)

"VM Thread" os_prio=0 tid=0x00007fed50080800 nid=0x432c runnable

"GC task thread#0 (ParallelGC)" os_prio=0 tid=0x00007fed5001e000 nid=0x432a runnable

"GC task thread#1 (ParallelGC)" os_prio=0 tid=0x00007fed50020000 nid=0x432b runnable

"VM Periodic Task Thread" os_prio=0 tid=0x00007fed500cd000 nid=0x4333 waiting on condition

JNI global references: 332

Heap

PSYoungGen total 74752K, used 57281K [0x000000076cf00000, 0x0000000776100000, 0x00000007c0000000)

eden space 64512K, 75% used [0x000000076cf00000,0x000000076feb57f8,0x0000000770e00000)

from space 10240K, 82% used [0x0000000770e00000,0x000000077163ac20,0x0000000771800000)

to space 10240K, 0% used [0x0000000775700000,0x0000000775700000,0x0000000776100000)

ParOldGen total 171008K, used 0K [0x00000006c6c00000, 0x00000006d1300000, 0x000000076cf00000)

object space 171008K, 0% used [0x00000006c6c00000,0x00000006c6c00000,0x00000006d1300000)

Metaspace used 18718K, capacity 19096K, committed 19328K, reserved 1067008K

class space used 1919K, capacity 2091K, committed 2176K, reserved 1048576K

  • another problem is that the eventbus actually was used (@onDisconnected Called onDisconnected with sessionObject: ) but the registered handler in @disconnect() was not fired:
10  1470994378524   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Using synchronous disconnect
10  1470994378524   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Stopping connector: tigase.jaxmpp.core.client.connector.ConnectorWrapper@5f98e400
10  1470994378524   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    setStage    Connector (oid=2144665602) state changed: connected->disconnecting
10  1470994378524   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateStream Terminating XMPP Stream
10  1470994378525   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    send    Send (oid=2144665602): </stream:stream>
10  1470994378525   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateAllWorkers Terminating all workers (oid=2144665602)
12  1470994378526   tigase.jaxmpp.j2se.connectors.socket.Worker$1   xmppStreamClosed    xmppStreamClosed()
12  1470994378526   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    setStage    Connector (oid=2144665602) state changed: disconnecting->disconnected
12  1470994378527   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    onStreamTerminate   Stream terminated
12  1470994378527   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    terminateAllWorkers Terminating all workers (oid=2144665602)
12  1470994378528   tigase.jaxmpp.j2se.connectors.socket.Worker run 1935634429 / Disconnecting: state=disconnected; isInterrupted():false; buffer=-1   Thread[Thread-3,5,main]
12  1470994378528   tigase.jaxmpp.j2se.connectors.socket.Worker interrupt   Worker Interrupted
12  1470994378528   tigase.jaxmpp.j2se.connectors.socket.Worker run Worker2 is interrupted
12  1470994378528   tigase.jaxmpp.j2se.connectors.socket.SocketConnector    workerTerminated    Worker terminated
10  1470994378528   tigase.jaxmpp.j2se.Jaxmpp   disconnect  Connector state: disconnected
10  1470994378530   tigase.jaxmpp.core.client.AbstractSessionObject clear   Clearing properties!
10  1470994378530   tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1    run Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
10  1470994378530   tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1    run Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
10  1470994378531   tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1    run Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$10 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
10  1470994378531   tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1    run Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
10  1470994378539   tigase.jaxmpp.j2se.Jaxmpp$2 onDisconnected  Called onDisconnected with sessionObject: AbstractSessionObject{properties={CONNECTOR#ENCRYPTED_KEY=Entry{scope=stream, value=true}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@6b31f86e}, SCRAM_SASL_DATA_KEY=Entry{scope=stream, value=tigase.jaxmpp.core.client.xmpp.modules.auth.scram.AbstractScram$Data@12549eeb}, userBareJid=Entry{scope=user, value=test_user_1@cluster-c.xmpp-test.net}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnected}, TLS_PEER_CERTIFICATE_KEY=Entry{scope=stream, value=[…

Full thread dump OpenJDK 64-Bit Server VM (25.51-b03 mixed mode):

"DestroyJavaVM" #24 prio=5 os_prio=0 tid=0x00007f8928009000 nid=0x4cf7 waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"pool-1-thread-1" #10 prio=5 os_prio=0 tid=0x00007f892878d800 nid=0x4d03 waiting on condition [0x00007f88f1a6d000]

java.lang.Thread.State: WAITING (parking)

at sun.misc.Unsafe.park(Native Method)

- parking to wait for  <0x000000076d638af8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)

at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)

at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)

at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)

at java.lang.Thread.run(Thread.java:745)

"Service Thread" #7 daemon prio=9 os_prio=0 tid=0x00007f89280ca800 nid=0x4d00 runnable [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"C1 CompilerThread1" #6 daemon prio=9 os_prio=0 tid=0x00007f89280c8000 nid=0x4cff waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" #5 daemon prio=9 os_prio=0 tid=0x00007f89280ba800 nid=0x4cfe waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" #4 daemon prio=9 os_prio=0 tid=0x00007f89280b8800 nid=0x4cfd waiting on condition [0x0000000000000000]

java.lang.Thread.State: RUNNABLE

"Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00007f8928087800 nid=0x4cfc in Object.wait() [0x00007f8918591000]

java.lang.Thread.State: WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x0000000770ec87f0> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)

- locked <0x0000000770ec87f0> (a java.lang.ref.ReferenceQueue$Lock)

at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)

at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)

"Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x00007f8928085800 nid=0x4cfb in Object.wait() [0x00007f8918692000]

java.lang.Thread.State: WAITING (on object monitor)

at java.lang.Object.wait(Native Method)

- waiting on <0x0000000770ec89a8> (a java.lang.ref.Reference$Lock)

at java.lang.Object.wait(Object.java:502)

at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:157)

- locked <0x0000000770ec89a8> (a java.lang.ref.Reference$Lock)

"VM Thread" os_prio=0 tid=0x00007f8928080800 nid=0x4cfa runnable

"GC task thread#0 (ParallelGC)" os_prio=0 tid=0x00007f892801e000 nid=0x4cf8 runnable

"GC task thread#1 (ParallelGC)" os_prio=0 tid=0x00007f8928020000 nid=0x4cf9 runnable

"VM Periodic Task Thread" os_prio=0 tid=0x00007f89280cd000 nid=0x4d01 waiting on condition

JNI global references: 341

Heap

PSYoungGen total 74752K, used 58142K [0x000000076cf00000, 0x0000000772200000, 0x00000007c0000000)

eden space 64512K, 77% used [0x000000076cf00000,0x000000076ff8bc40,0x0000000770e00000)

from space 10240K, 82% used [0x0000000770e00000,0x000000077163bc40,0x0000000771800000)

to space 10240K, 0% used [0x0000000771800000,0x0000000771800000,0x0000000772200000)

ParOldGen total 171008K, used 8K [0x00000006c6c00000, 0x00000006d1300000, 0x000000076cf00000)

object space 171008K, 0% used [0x00000006c6c00000,0x00000006c6c02000,0x00000006d1300000)

Metaspace used 18727K, capacity 19164K, committed 19328K, reserved 1067008K

class space used 1919K, capacity 2091K, committed 2176K, reserved 1048576K

#51 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

I looked over this logs and thread are waiting but I was not able to find deadlock or lock. After talk with %bmalkow he found that event bus executor thread is not set as deamon and due to that process is kept alive. After testing this change on n1.xmpp-test.net, I was not able to replicate this issue. Right now %bmalkow is working on proper solution to this issue.

#52 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Eric Dziewa

%eric New version with fixes for EventBus executor thread issue which was found today is ready - I triggered build on Jenkins so it is newest snapshot build.

#53 Updated by Eric Dziewa over 2 years ago

  • Assignee changed from Eric Dziewa to Andrzej Wójcik

I'm using Tigase b4279 from today along with jaxmpp b633 from today. The not_authorized problem still exists.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b633/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=716487794).
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: null->connecting
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: connecting->connected
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1490103113 Starting Thread[Socket-Worker-Thread,5,main]
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=716487794)
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=4b7556b2[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=52300]]]
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 90685245401753889542390790907259541740419346091969829162425714387247071159727672458542290314444712094296058160414005219252993455920541283987057735988953829494320033205599079441515686500586484323997195269597169561380919662724134146116940883372074273457027654908321393013574384464879779497861934862599860153969
  public exponent: 65537
  Validity: [From: Fri Aug 12 11:56:03 EDT 2016,
               To: Sat Aug 12 11:56:03 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    57adf193]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 30 65 A6 D6 68 B9 63 78   90 BE 3D 75 53 79 17 9B  0e..h.cx..=uSy..
0010: C1 B1 66 29 9E 89 F6 D6   D8 EF 4A D0 1B DD 8E 8F  ..f)......J.....
0020: 3E 01 44 F3 4E EE 49 27   02 D6 22 15 21 E5 88 96  >.D.N.I'..".!...
0030: B8 DC F8 52 E1 28 8A 41   D5 9E 74 8E 0C C6 16 38  ...R.(.A..t....8
0040: DC 4D 86 6F CF E1 A6 1B   60 72 96 93 4D CC 3E DC  .M.o....`r..M.>.
0050: 75 E8 B4 1F 5C 73 F1 ED   A2 B1 E0 E4 D3 51 49 99  u...\s.......QI.
0060: 9D 10 0A 5C FA 74 80 25   98 00 42 A3 23 7D B5 5C  ...\.t.%..B.#..\
0070: D7 DE 8A 16 F4 37 AF 84   B6 6F CC 34 F1 B2 3B 71  .....7...o.4..;q

]]
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9QWYzVXh6N1k5T2ZFcHloZjhrQUQ=</auth>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9QWYzVXh6N1k5T2ZFcHloZjhrQUQ=</auth>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthStartHandler$AuthStartEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1BZjNVeHo3WTlPZkVweWhmOGtBRGw4NXloNXRvdnNGTWY5aGFnYk5LLHM9T1lyQ3JDWDV0d3FqUWc9PSxpPTQwOTY=</challenge>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlCdSsvdmUrL3ZlKy92VVB2djczdnY3MHlBTysvdlJkbkdDQTk3Nys5NzcrOTc3Kzk3Nys5NzcrOU91Ky92VlJqUm1YdnY3M3Z2NzEzZHc9PSxyPUFmM1V4ejdZOU9mRXB5aGY4a0FEbDg1eWg1dG92c0ZNZjloYWdiTksscD1acy9JYVRDU3VsTGxONWxseWt0dmZZY1ZmeEk9</response>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlCdSsvdmUrL3ZlKy92VVB2djczdnY3MHlBTysvdlJkbkdDQTk3Nys5NzcrOTc3Kzk3Nys5NzcrOU91Ky92VlJqUm1YdnY3M3Z2NzEzZHc9PSxyPUFmM1V4ejdZOU9mRXB5aGY4a0FEbDg1eWg1dG92c0ZNZjloYWdiTksscD1acy9JYVRDU3VsTGxONWxseWt0dmZZY1ZmeEk9</response>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Channel bindings does not match</text></failure>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processFailure
FINE: Failure with condition: not_authorized
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthFailedHandler$SaslAuthFailedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.Jaxmpp onException
FINE: Catching exception
tigase.jaxmpp.core.client.exceptions.JaxmppException: Unauthorized with condition=not_authorized
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic.processAuthFailed(AbstractSocketXmppSessionLogic.java:160)
        at tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$3.onAuthFailed(AbstractSocketXmppSessionLogic.java:81)
        at tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent.dispatch(AuthModule.java:260)
        at tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent.dispatch(AuthModule.java:249)
        at tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1.run(ThreadSafeEventBus.java:71)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)

Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: connected->disconnecting
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateStream
FINE: Terminating XMPP Stream
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): </stream:stream>
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=716487794)
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthFailedHandler$AuthFailedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StateChangedHandler$StateChangedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthFailedHandler$SaslAuthFailedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamClosed
FINEST: xmppStreamClosed()
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: disconnecting->disconnected
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector onStreamTerminate
FINE: Stream terminated
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector terminateAllWorkers
FINEST: Terminating all workers (oid=716487794)
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StateChangedHandler$StateChangedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 1490103113 / Disconnecting: state=disconnected; isInterrupted():true; buffer=16   Thread[Socket-Worker-Thread,5,main]
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker interrupt
FINE: Worker Interrupted
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: Worker2 is interrupted
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector workerTerminated
FINEST: Worker terminated
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$6 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$10 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent
Aug 12, 2016 1:27:11 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent
[root@n1 ~]# 

The line I'm trying to print has disappeared when I am able to login.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b633/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.AbstractSessionObject clear
FINE: Clearing properties!
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.Jaxmpp createConnector
INFO: Using SocketConnector
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINE: Start connector (oid=716487794).
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager$1 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$2 with event class tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: null->connecting
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINER: Preparing connection to c01.xmpp-test.net:5222
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
INFO: Opening connection to c01.xmpp-test.net/198.100.157.104:5222
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
FINEST: Starting worker...
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector setStage
FINE: Connector (oid=716487794) state changed: connecting->connected
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector start
CONFIG: Whitespace ping period is setted to 175000ms
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.Worker run
FINEST: 792771270 Starting Thread[Socket-Worker-Thread,5,main]
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector startTLS
FINE: Start TLS (oid=716487794)
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Proceeding TLS
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector proceedTLS
FINE: Start handshake
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector$2 handshakeCompleted
INFO: TLS completed javax.net.ssl.HandshakeCompletedEvent[source=7b0d1669[TLS_RSA_WITH_AES_128_CBC_SHA256: Socket[addr=c01.xmpp-test.net/198.100.157.104,port=5222,localport=52811]]]
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier verify
FINEST: Peer certificates: [[
[
  Version: V1
  Subject: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 90685245401753889542390790907259541740419346091969829162425714387247071159727672458542290314444712094296058160414005219252993455920541283987057735988953829494320033205599079441515686500586484323997195269597169561380919662724134146116940883372074273457027654908321393013574384464879779497861934862599860153969
  public exponent: 65537
  Validity: [From: Fri Aug 12 11:56:03 EDT 2016,
               To: Sat Aug 12 11:56:03 EDT 2017]
  Issuer: CN=cluster-c.xmpp-test.net, CN=*.cluster-c.xmpp-test.net, EMAILADDRESS=admin@tigase.org, OU=XMPP Service, O=Tigase.org
  SerialNumber: [    57adf193]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 30 65 A6 D6 68 B9 63 78   90 BE 3D 75 53 79 17 9B  0e..h.cx..=uSy..
0010: C1 B1 66 29 9E 89 F6 D6   D8 EF 4A D0 1B DD 8E 8F  ..f)......J.....
0020: 3E 01 44 F3 4E EE 49 27   02 D6 22 15 21 E5 88 96  >.D.N.I'..".!...
0030: B8 DC F8 52 E1 28 8A 41   D5 9E 74 8E 0C C6 16 38  ...R.(.A..t....8
0040: DC 4D 86 6F CF E1 A6 1B   60 72 96 93 4D CC 3E DC  .M.o....`r..M.>.
0050: 75 E8 B4 1F 5C 73 F1 ED   A2 B1 E0 E4 D3 51 49 99  u...\s.......QI.
0060: 9D 10 0A 5C FA 74 80 25   98 00 42 A3 23 7D B5 5C  ...\.t.%..B.#..\
0070: D7 DE 8A 16 F4 37 AF 84   B6 6F CC 34 F1 B2 3B 71  .....7...o.4..;q

]]
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-1-PLUS</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule login
FINER: Authenticating with SASL Non-SASL
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule login
FINE: Try login with SASL
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9MUJnTkZ0Znp1emtuVGk0bWlpRWk=</auth>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1-PLUS">cD10bHMtdW5pcXVlLCxuPXRlc3RfdXNlcl8xLHI9MUJnTkZ0Znp1emtuVGk0bWlpRWk=</auth>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthStartHandler$SaslAuthStartEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthStartHandler$AuthStartEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0xQmdORnRmenV6a25UaTRtaWlFaUFKYkFjbVVvc0o5R2JEak1pekVsLHM9YlFSR3Z1c1NkVTRXaHc9PSxpPTQwOTY=</challenge>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlERER2djcxZFZuYnZ2NzFSNzcrOTc3KzlFamhFNzcrOWUrKy92VjN2djcwSTc3KzlFQm52djczdnY3MGtGRHJ2djczdnY3MDMscj0xQmdORnRmenV6a25UaTRtaWlFaUFKYkFjbVVvc0o5R2JEak1pekVsLHA9TlV4NWREQmxhL0QvWW1oUkpmVG5weWROSjV3PQ==</response>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1jRDEwYkhNdGRXNXBjWFZsTEN4WDc3KzlERER2djcxZFZuYnZ2NzFSNzcrOTc3KzlFamhFNzcrOWUrKy92VjN2djcwSTc3KzlFQm52djczdnY3MGtGRHJ2djczdnY3MDMscj0xQmdORnRmenV6a25UaTRtaWlFaUFKYkFjbVVvc0o5R2JEak1pekVsLHA9TlV4NWREQmxhL0QvWW1oUkpmVG5weWROSjV3PQ==</response>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1TZnpRM1FhY2trTmsvbkI0QzNlK0NPY1ZYeVU9</success>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule processSuccess
FINE: Authenticated
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthSuccessHandler$SaslAuthSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule$SaslAuthSuccessHandler$SaslAuthSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$4 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthSuccessHandler$AuthSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector restartStream
FINEST: Restarting XMPP Stream
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <stream:stream to='cluster-c.xmpp-test.net' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule$AuthSuccessHandler$AuthSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.Worker$1 xmppStreamOpened
FINEST: xmppStreamOpened()
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></features>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="2vseAf" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <iq xmlns="jabber:client" id="2vseAf" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>bread</resource></bind></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <iq xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" id="2vseAf" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>test_user_1@cluster-c.xmpp-test.net/bread</jid></bind></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule$StreamFeaturesReceivedHandler$StreamFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.JaxmppCore$LoggedInHandler$LoggedInEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$5 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq xmlns="jabber:client" id="ZdynVf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <iq xmlns="jabber:client" id="ZdynVf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <iq xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" id="ZdynVf" type="result"/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.ResourceBinderModule$ResourceBindSuccessHandler$ResourceBindSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.connector.AbstractSocketXmppSessionLogic$7 with event class tigase.jaxmpp.core.client.xmpp.modules.SessionEstablishmentModule$SessionEstablishmentSuccessHandler$SessionEstablishmentSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <iq to="cluster-c.xmpp-test.net" id="GtiAlX" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <iq to="cluster-c.xmpp-test.net" id="GtiAlX" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <iq xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" from="cluster-c.xmpp-test.net" id="GtiAlX" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 7.1.0-SNAPSHOT-b4279/660456ca (2016-08-12/08:05:18)" type="im" category="component"/><identity name="Tigase ver. 7.1.0-SNAPSHOT-b4279/660456ca (2016-08-12/08:05:18)" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.SessionEstablishmentModule$SessionEstablishmentSuccessHandler$SessionEstablishmentSuccessEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule with event class tigase.jaxmpp.core.client.XmppSessionLogic$XmppSessionEstablishedHandler$XmppSessionEstablishedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <presence/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.XmppSessionLogic$XmppSessionEstablishedHandler$XmppSessionEstablishedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$1 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$BeforePresenceSendHandler$BeforePresenceSendEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$BeforePresenceSendHandler$BeforePresenceSendEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.disco.DiscoveryModule$ServerFeaturesReceivedHandler$ServerFeaturesReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINEST: Presence received from test_user_1@cluster-c.xmpp-test.net/bread :: <presence xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net" from="test_user_1@cluster-c.xmpp-test.net/bread"/>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule process
FINER: Presence online from test_user_1@cluster-c.xmpp-test.net/bread
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$3 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactChangedPresenceHandler$ContactChangedPresenceEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactChangedPresenceHandler$ContactChangedPresenceEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.capabilities.CapabilitiesModule$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactAvailableHandler$ContactAvailableEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.j2se.Jaxmpp$2 with event class tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$ContactAvailableHandler$ContactAvailableEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1471024176736</status></presence>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <presence to="tigasemonitor@cluster-c.xmpp-test.net/butter"><show>away</show><status>1471024176736</status></presence>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector processElement
FINEST: Recv (oid=716487794): <message xmlns="jabber:client" to="test_user_1@cluster-c.xmpp-test.net/bread" from="tigasemonitor@cluster-c.xmpp-test.net/butter" id="uoLEqT"><subject/><body>26</body></message>
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.JaxmppCore$8 with event class tigase.jaxmpp.core.client.Connector$StanzaReceivedHandler$StanzaReceivedEvent
Aug 12, 2016 1:49:36 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigaseJaXMPPclients$4 with event class tigase.jaxmpp.core.client.xmpp.modules.chat.MessageModule$MessageReceivedHandler$MessageReceivedEvent
Aug 12, 2016 1:49:38 PM tigase.jaxmpp.core.client.JaxmppCore$2 write
FINEST: SENT: <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="89iHNA"><subject></subject><body>1471024178764</body></message>
Aug 12, 2016 1:49:38 PM tigase.jaxmpp.j2se.connectors.socket.SocketConnector send
FINEST: Send (oid=716487794): <message to="tigasemonitor@cluster-c.xmpp-test.net/butter" id="89iHNA"><subject></subject><body>1471024178764</body></message>
Aug 12, 2016 1:49:38 PM tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$1 run
FINEST: Calling handler class class tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule$1 with event class tigase.jaxmpp.core.client.Connector$StanzaSendingHandler$StanzaSendingEvent
[root@n1 ~]#

Turn debugging off...

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b633/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor

[root@n1 ~]#

Use b621 instead of b633.

[root@n1 ~]# groovy -cp jaxmpp-dist-3.2.0-SNAPSHOT-b621/single/jaxmpp-j2se-singlejar.jar tigaseJaXMPPclients.groovy process test_user_1 test_user_1 c01.xmpp-test.net cluster-c.xmpp-test.net tigasemonitor


1079 30 6 

</stream:stream>
^C[root@n1 ~]# 

#54 Updated by Andrzej Wójcik IoT 1 CloudTigaseTeam over 2 years ago

  • Assignee changed from Andrzej Wójcik to Bartosz Malkowski

%eric This is caused by the fact that due to todays changes when processing on main thread is finished then application is closed as every other thread which works in background will not keep application alive any more. Issue which you experienced earlier (with application hangs) was caused by fact that background thread was keeping application alive and we changed this.

Now if you want to keep application alive you need to take care about it on your own. In this case I would suggest to replace following lines at the end of file:

//      Thread.sleep(20000)
//      contact.disconnect()
//      printf( printMe )
//      System.exit(0)

with following lines:

        Thread.sleep(20000)
//      contact.disconnect()
//      printf( printMe )
//      System.exit(0)

which will keep application alive for 20 seconds.

I'm assigning task to %bmalkow as he will need to look into issue with not authorized exception.

#55 Updated by Eric Dziewa over 2 years ago

  • Due date changed from 2016-08-12 to 2016-09-14
  • Status changed from Feedback to Closed
  • Assignee changed from Bartosz Malkowski to Eric Dziewa

Latest nightly seems to be fixed. Thanks for helping everyone.

Also available in: Atom PDF