Testing reconnections - #4266

2019-11-27 21:11:34 |

2019-11-27 21:11:34 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 21:11:34 | ------------------------------------

2019-11-27 21:11:34 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:34 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 21:11:34 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2plMmt5YjA1MzAscj1lSHIyb21IOHZsOHdzanAzcWJONg==</auth>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1lSHIyb21IOHZsOHdzanAzcWJONkNsTDVCUXR1azdNYVZDcExVa3ZSLHM9YkRaZnVIMkcwMis4OVE9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZUhyMm9tSDh2bDh3c2pwM3FiTjZDbEw1QlF0dWs3TWFWQ3BMVWt2UixwPTNpODl1Y2Y3cFMzSkY1eHB5dVRVTGVTSHJ2OHBQYmdZT1NkbVI4eGtjaTQ9</response>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1RM24zamw3K3VsSzQvSjlkbXRPVVgrMFBiU3VwV0tvSC9mdFE0akxhQXhvPQ==</success>

2019-11-27 21:11:34 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <iq xmlns="jabber:client" id="QxxKIxeFCj4AgAodDjLQScH" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <iq to="jaxmpp__je2kyb0530@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="QxxKIxeFCj4AgAodDjLQScH"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__je2kyb0530@localhost/993927746-tigase-281</jid></bind></iq>

2019-11-27 21:11:34 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 >> <iq to="localhost" id="LrdSZjUZhMFubEncQMCslTP" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 >> <iq id="JOioQLLR0bFydAn29KWY0eT" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 << <iq to="jaxmpp__je2kyb0530@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="JOioQLLR0bFydAn29KWY0eT"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 << <iq from="localhost" to="jaxmpp__je2kyb0530@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="LrdSZjUZhMFubEncQMCslTP"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><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:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><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>

2019-11-27 21:11:34 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-281 << <presence to="jaxmpp__je2kyb0530@localhost" xmlns="jabber:client" from="jaxmpp__je2kyb0530@localhost/993927746-tigase-281"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 21:11:36 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-27 21:11:36 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-11-27 21:11:36 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-27 21:11:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-27 21:11:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 21:11:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-27 21:11:38 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:38 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2plMmt5YjA1MzAscj1EUUpYZ3NXcm51bkowaUJMa2d4eg==</auth>

2019-11-27 21:11:38 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1EUUpYZ3NXcm51bkowaUJMa2d4em5yUDRYTnhvaXJ0YWVPUE9yNVgyLHM9YkRaZnVIMkcwMis4OVE9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:38 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RFFKWGdzV3JudW5KMGlCTGtneHpuclA0WE54b2lydGFlT1BPcjVYMixwPTlSSG02ZTZyUk5DUzFLa1QxaGRHNndOL1RDZ0JnZllmZGR5eUlpNGlQc3c9</response>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1OQlVhR1c5cFJNaFI0blI1Yy95QjJUZVMxSlFBL3pFMzM3RmRhcFQ1bmVJPQ==</success>

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost >> <iq xmlns="jabber:client" id="aAA00qhCjR3oXdEpY9AfWkK" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost << <iq to="jaxmpp__je2kyb0530@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="aAA00qhCjR3oXdEpY9AfWkK"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__je2kyb0530@localhost/993927746-tigase-282</jid></bind></iq>

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-282 >> <iq to="localhost" id="5K2DIJJDJsk5exjXczr6jML" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-282 >> <iq id="QCCU2bwfMDISQKIRVTVawuh" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__je2kyb0530@localhost/993927746-tigase-282 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-27 21:11:39 |

2019-11-27 21:11:39 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 21:11:39 | ------------------------------------

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: null->connecting

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] DNS entry stored in session object: localhost:5222

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Preparing connection to [localhost:5222]

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: connecting->connected

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Whitespace ping period is setted to nullms

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3762] Start TLS

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3764] Proceeding TLS

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3764] Start handshake

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QycTAxZDA1MzEscj1BcnJiT1hmSzNOMHJLbE1MVHdyeA==</auth>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1BcnJiT1hmSzNOMHJLbE1MVHdyeEJmSTNzZmlKVzhpbUF4cjZEbjhGLHM9T0ppdzJ1cXU5WnN1REE9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QXJyYk9YZkszTjByS2xNTFR3cnhCZkkzc2ZpSlc4aW1BeHI2RG44RixwPWJ2SURQN0EvV3hyZWpBY3R2UU10dDYyUGxCRWo3TjByd2Ryck1XR0ZpTnc9</response>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13MVUwSjZqdzFwTEduZ1hxSkFjNmZWWlA4QXdWQVAxM3h2NXg2cjY0aUlrPQ==</success>

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <iq xmlns="jabber:client" id="FMYurSaLAfJNSV9w9s7w7GO" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="FMYurSaLAfJNSV9w9s7w7GO"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__t2q01d0531@localhost/993927746-tigase-283</jid></bind></iq>

2019-11-27 21:11:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="pJNfzACkIEJFnT6oeLNLZYm" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="pJNfzACkIEJFnT6oeLNLZYm"/>

2019-11-27 21:11:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 >> <iq to="localhost" id="LBncDBu9BqX4lY1uqWW6DtZ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 >> <iq id="Y0yVWW6DtZ7yi2n1dTMYnOH" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="79f8dc15-6708-4e30-9633-3dc9ffb6145a"/>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 << <iq from="localhost" to="jaxmpp__t2q01d0531@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="LBncDBu9BqX4lY1uqWW6DtZ"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><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:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><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>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="Y0yVWW6DtZ7yi2n1dTMYnOH"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:39 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-283 << <presence to="jaxmpp__t2q01d0531@localhost" xmlns="jabber:client" from="jaxmpp__t2q01d0531@localhost/993927746-tigase-283"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: connected->disconnecting

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Terminating XMPP Stream

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3764] Connector  state changed: disconnecting->disconnected

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3764] Stream terminated

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:41 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-27 21:11:41 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Connector  state changed: null->connecting

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] DNS entry stored in session object: localhost:5222

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Preparing connection to [localhost:5222]

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Connector  state changed: connecting->connected

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Whitespace ping period is setted to nullms

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3762] Start TLS

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3771] Proceeding TLS

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3771] Start handshake

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QycTAxZDA1MzEscj1xek9xbXdaUDBIT20wZGlnRk41MQ==</auth>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1xek9xbXdaUDBIT20wZGlnRk41MTZ6RkxFc3N1MlZKVG9RMEhTUmNoLHM9T0ppdzJ1cXU5WnN1REE9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cXpPcW13WlAwSE9tMGRpZ0ZONTE2ekZMRXNzdTJWSlRvUTBIU1JjaCxwPW1rRVFDdlJsWnk1aW42SDBqOXJoZWpTT3ZhQVdJY1hnL21tNnRDaWhKN0k9</response>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0xaXplekV6TzlYZmIzSnp2R3Z2L3ZZT0tjeU9SVzM4Z3U5czlsTEIxbk1RPQ==</success>

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost >> <iq xmlns="jabber:client" id="zCmeMKWbDrlGMZ29xp6n3kL" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="zCmeMKWbDrlGMZ29xp6n3kL"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__t2q01d0531@localhost/993927746-tigase-284</jid></bind></iq>

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="AJF1eU7vbPEIRMDvlY7xiHy" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="AJF1eU7vbPEIRMDvlY7xiHy"/>

2019-11-27 21:11:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 >> <iq to="localhost" id="MGuAviLPSRSXctoeyayxhBl" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 >> <iq id="d2wQKzn5s5tar2hQLYoSOQ7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="8c202f53-189b-47d9-b18c-97e026fff29f"/>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 << <iq from="localhost" to="jaxmpp__t2q01d0531@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="MGuAviLPSRSXctoeyayxhBl"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><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:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><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>

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 << <iq to="jaxmpp__t2q01d0531@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="d2wQKzn5s5tar2hQLYoSOQ7"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3771] Connector  state changed: connected->disconnected

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3771] Stream terminated

2019-11-27 21:11:43 | jaxmpp_ :: jaxmpp__t2q01d0531@localhost/993927746-tigase-284 << <presence to="jaxmpp__t2q01d0531@localhost" xmlns="jabber:client" from="jaxmpp__t2q01d0531@localhost/993927746-tigase-284"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:44 |

2019-11-27 21:11:44 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 21:11:44 | ------------------------------------

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2czenU3NTA1MzIscj1rc0xlM0dURlVoUzRxSkN2OWdjTg==</auth>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1rc0xlM0dURlVoUzRxSkN2OWdjTkhmd0E1cUszYUFaV2lRazBsamlHLHM9YkJqK0JFM0JWUmEyQWc9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9a3NMZTNHVEZVaFM0cUpDdjlnY05IZndBNXFLM2FBWldpUWswbGppRyxwPU14dXJwREIwRkdnWlF6eENRNGlPUTlJclcxSDdTVXRsQkMza2dMYXJoYkU9</response>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1EYnRpT2xPSmQ4YnBPNzVIMjIrVjN5a1RDRSs3SWU4bWovZzB0dkdUSUhrPQ==</success>

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <iq xmlns="jabber:client" id="yTXkNU0sdHzBcO3ap3ravjQ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="yTXkNU0sdHzBcO3ap3ravjQ"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__g3zu750532@localhost/993927746-tigase-285</jid></bind></iq>

2019-11-27 21:11:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="mV1wcBu3yycs8HBfDAmaDxf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="mV1wcBu3yycs8HBfDAmaDxf"/>

2019-11-27 21:11:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 >> <iq to="localhost" id="saIfAeGNNDp8sm1jN36CFoR" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 >> <iq id="KaGNFzteDzmPOPWXmaqU2vd" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="3c20d36e-89f1-4a20-b4ee-7fe3d53684e8"/>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 << <iq from="localhost" to="jaxmpp__g3zu750532@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="saIfAeGNNDp8sm1jN36CFoR"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><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:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><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>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="KaGNFzteDzmPOPWXmaqU2vd"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:44 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-285 << <presence to="jaxmpp__g3zu750532@localhost" xmlns="jabber:client" from="jaxmpp__g3zu750532@localhost/993927746-tigase-285"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 21:11:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-27 21:11:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-27 21:11:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-11-27 21:11:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-27 21:11:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><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-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2czenU3NTA1MzIscj1GeHhtYmdIY2cxNWhTOGE3UHBNNA==</auth>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GeHhtYmdIY2cxNWhTOGE3UHBNNDg5dVhUTkwybnA0em1WM3hkTTlELHM9YkJqK0JFM0JWUmEyQWc9PSxpPTQwOTY=</challenge>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Rnh4bWJnSGNnMTVoUzhhN1BwTTQ4OXVYVE5MMm5wNHptVjN4ZE05RCxwPUVEc2N3RzJkUmRHSS9EUEErMGZ1VzlGcHIzSmRCbVZmaEs3UHpuYTdITEE9</response>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1uUUxLSmFnRXNaL2gxbjVONFU1eWEwMHNsZWdqSTVOSHlUK0JvRkcxUjh3PQ==</success>

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><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"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost >> <iq xmlns="jabber:client" id="YcKjIucPRLFEuq9zj5aKVUT" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-286" type="result" xmlns="jabber:client" id="YcKjIucPRLFEuq9zj5aKVUT"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__g3zu750532@localhost/993927746-tigase-286</jid></bind></iq>

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 >> <iq xmlns="jabber:client" id="ZdLZ2i1fxdKGOV7phywt05p" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-286" type="result" xmlns="jabber:client" id="ZdLZ2i1fxdKGOV7phywt05p"/>

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 >> <iq to="localhost" id="kXXAoTW5sZqiGq4zlJBweU7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 >> <iq id="ZfN3ayxhBl0cQZfFNAABgBr" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="e84485a1-245e-4614-bc19-15d6ed81cab5"/>

2019-11-27 21:11:48 | jaxmpp_ :: jaxmpp__g3zu750532@localhost/993927746-tigase-286 << <iq to="jaxmpp__g3zu750532@localhost/993927746-tigase-286" type="result" xmlns="jabber:client" id="ZfN3ayxhBl0cQZfFNAABgBr"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2019-11-27 21:11:48 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 21:11:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected