Testing reconnections - #4266

Test duration: 15.591s

Passed Tests
tigase.tests.jaxmpp.TestReconnections
testMultipleReconnectionsBosh 5.338s
2020-01-02 19:56:46 |

2020-01-02 19:56:46 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-01-02 19:56:46 | ------------------------------------

2020-01-02 19:56:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:56:46 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-01-02 19:56:46 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@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>

2020-01-02 19:56:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:56:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3ZnNzdnMTA1MzAscj1XcmtiUDhDblhZcGhId0Voc3NnMg==</auth>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1XcmtiUDhDblhZcGhId0Voc3NnMnJ4czRxVjJPenI1cTcwR1JudG5WLHM9VWlyWEM3WWFVVEZZZnc9PSxpPTQwOTY=</challenge>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9V3JrYlA4Q25YWXBoSHdFaHNzZzJyeHM0cVYyT3pyNXE3MEdSbnRuVixwPWVPdEFHQU4yWXpTaVlpMENlSGdWeDEwUFZoRFNDK3k0ZlUvTjhFczFENkE9</response>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1ORTliMmNtTnV0SUtpeEY3Si9GSC9aN3J1UU5DWExOVWJQcEx5Z085eWJjPQ==</success>

2020-01-02 19:56:46 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <iq xmlns="jabber:client" id="ayIW1jXUhIugGm1mNEzdPSa" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <iq id="ayIW1jXUhIugGm1mNEzdPSa" xmlns="jabber:client" to="jaxmpp__vg77g10530@localhost/943755961-tigase-280" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vg77g10530@localhost/943755961-tigase-280</jid></bind></iq>

2020-01-02 19:56:46 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 >> <iq to="localhost" id="1xDFzoO7FDvgFtdLwvao4iA" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 >> <iq id="rpdcCEEp26r8i5BvfKDp5v0" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 << <iq id="1xDFzoO7FDvgFtdLwvao4iA" xmlns="jabber:client" to="jaxmpp__vg77g10530@localhost/943755961-tigase-280" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><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 category="pubsub" type="pep"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 << <iq id="rpdcCEEp26r8i5BvfKDp5v0" xmlns="jabber:client" to="jaxmpp__vg77g10530@localhost/943755961-tigase-280" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:46 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-280 << <presence xmlns="jabber:client" to="jaxmpp__vg77g10530@localhost" from="jaxmpp__vg77g10530@localhost/943755961-tigase-280"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-01-02 19:56:48 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2020-01-02 19:56:48 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2020-01-02 19:56:48 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-01-02 19:56:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-01-02 19:56:50 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:56:50 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-01-02 19:56:50 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@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>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3ZnNzdnMTA1MzAscj1wOWRUZWNVZGl5UU5ySWFqWUJBMw==</auth>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1wOWRUZWNVZGl5UU5ySWFqWUJBM3hTTVk4WGpQV2tvWjJVVll0Y3FtLHM9VWlyWEM3WWFVVEZZZnc9PSxpPTQwOTY=</challenge>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cDlkVGVjVWRpeVFOcklhallCQTN4U01ZOFhqUFdrb1oyVVZZdGNxbSxwPWl1YjVxMjlpRW4rU1BOWUFrMFNYZVJ3UkxKNWQybXRaS1c4dkFkbmpCL1k9</response>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1MeDNXNXFaUTFrbHFPZU9lNzQ3Zkg4U3d2RWdBV0tNSDVpZFN4QzcwbnpRPQ==</success>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost >> <iq xmlns="jabber:client" id="uLfdxiCtcql7xjSTV7z8JCi" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost << <iq id="uLfdxiCtcql7xjSTV7z8JCi" xmlns="jabber:client" to="jaxmpp__vg77g10530@localhost/943755961-tigase-281" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vg77g10530@localhost/943755961-tigase-281</jid></bind></iq>

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-281 >> <iq to="localhost" id="WDP6BqgSRWU3mKZp7tcxdHJ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-281 >> <iq id="0umjExkGR9Br8pXbHofEm0a" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__vg77g10530@localhost/943755961-tigase-281 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

testMultipleReconnectionsSocket 4.473s
2020-01-02 19:56:51 |

2020-01-02 19:56:51 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-01-02 19:56:51 | ------------------------------------

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: null->connecting

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] DNS entry stored in session object: localhost:5222

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Preparing connection to [localhost:5222]

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: connecting->connected

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Whitespace ping period is setted to nullms

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@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>

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3758] Start TLS

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3760] Proceeding TLS

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3760] Start handshake

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@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>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFmM2psYjA1MzEscj1GSVVQMlQ2eW5tcTdDR1JqWHpZSA==</auth>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GSVVQMlQ2eW5tcTdDR1JqWHpZSG5Ua1lDUmJGaFhkM1ZSR0hnb3hLLHM9NVdLbHZ6cUlnT1NkNnc9PSxpPTQwOTY=</challenge>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RklVUDJUNnlubXE3Q0dSalh6WUhuVGtZQ1JiRmhYZDNWUkdIZ294SyxwPUt6cDV4eGNCbEEvTDhpYVI0dlV6dFBPQ0dYM3RsVTZuNU5YRklPd3Y4MzA9</response>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1pdktUZXBPQTNpU2d0NTF3VXZwR2RuNlArdTdjdFk3MUZhWGVEcHNabzJNPQ==</success>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <iq xmlns="jabber:client" id="jJXrS3qVU5fT0qcveudGohZ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <iq id="jJXrS3qVU5fT0qcveudGohZ" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-282" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1f3jlb0531@localhost/943755961-tigase-282</jid></bind></iq>

2020-01-02 19:56:51 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 >> <iq xmlns="jabber:client" id="9HPhHxl12xewkOCxoRMPJRW" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 << <iq id="9HPhHxl12xewkOCxoRMPJRW" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-282" type="result"/>

2020-01-02 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 >> <iq to="localhost" id="ft74iEAkEBw7iRNZcOY3kGB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 << <enabled id="905f9dd5-69e9-498a-8e0c-dcf552183565" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 >> <iq id="eP9FNKyoWS3qVU5fT0qcveu" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 << <iq id="ft74iEAkEBw7iRNZcOY3kGB" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-282" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><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 category="pubsub" type="pep"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2020-01-02 19:56:51 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 << <iq id="eP9FNKyoWS3qVU5fT0qcveu" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-282" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:52 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-282 << <presence xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost" from="jaxmpp__1f3jlb0531@localhost/943755961-tigase-282"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-01-02 19:56:54 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector  state changed: connected->disconnecting

2020-01-02 19:56:54 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Terminating XMPP Stream

2020-01-02 19:56:54 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-01-02 19:56:54 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-01-02 19:56:54 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3760] Connector  state changed: disconnecting->disconnected

2020-01-02 19:56:54 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-01-02 19:56:54 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Connector  state changed: null->connecting

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] DNS entry stored in session object: localhost:5222

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Preparing connection to [localhost:5222]

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Connector  state changed: connecting->connected

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=1] Whitespace ping period is setted to nullms

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@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>

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3758] Start TLS

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3765] Proceeding TLS

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3765] Start handshake

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@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>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFmM2psYjA1MzEscj10Z0hBNVpVQjFSQlcyMkJUd3Fncg==</auth>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj10Z0hBNVpVQjFSQlcyMkJUd3FncmRyWkNMQU1FSG1HT3Z2dUZRSGZwLHM9NVdLbHZ6cUlnT1NkNnc9PSxpPTQwOTY=</challenge>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dGdIQTVaVUIxUkJXMjJCVHdxZ3JkclpDTEFNRUhtR092dnVGUUhmcCxwPThYZmdYRWthY3ZmbzVEcXB3TS81OURGcHpDVnNzNkVXeStIb3oxYzhCbWM9</response>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1IUExPNVdodk81STdGMDVSeEdqcWVCemxsQjhWQ3hFNmRBcGtCQ0RLOWNvPQ==</success>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost >> <iq xmlns="jabber:client" id="jN1z8zsZ2lLV4ys1gylWUj3" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost << <iq id="jN1z8zsZ2lLV4ys1gylWUj3" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-283" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1f3jlb0531@localhost/943755961-tigase-283</jid></bind></iq>

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 >> <iq xmlns="jabber:client" id="eRbJV04q00jWXYdvp2ukLKw" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 << <iq id="eRbJV04q00jWXYdvp2ukLKw" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-283" type="result"/>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 >> <iq to="localhost" id="KScLZ8kW24rct0hDFywoT02" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 << <enabled id="ef45ba5b-4a1c-4c93-840d-46d161c5e428" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 >> <iq id="bHDMPZYpY0iKuhSPIHtlUeO" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 << <iq id="KScLZ8kW24rct0hDFywoT02" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-283" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><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 category="pubsub" type="pep"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 << <iq id="bHDMPZYpY0iKuhSPIHtlUeO" xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost/943755961-tigase-283" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__1f3jlb0531@localhost/943755961-tigase-283 << <presence xmlns="jabber:client" to="jaxmpp__1f3jlb0531@localhost" from="jaxmpp__1f3jlb0531@localhost/943755961-tigase-283"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3765] Connector  state changed: connected->disconnected

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=588 thread=3765] Stream terminated

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testMultipleReconnectionsWebSocket 4.650s
2020-01-02 19:56:56 |

2020-01-02 19:56:56 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-01-02 19:56:56 | ------------------------------------

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@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>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3c3ampzYTA1MzIscj1IREUwaE1EeDlYVlBLRm51UGRxTg==</auth>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IREUwaE1EeDlYVlBLRm51UGRxTm05VGk5UVFiZGVMTkFmZWRjV0FaLHM9cUpQaDZJa2tnM2RERkE9PSxpPTQwOTY=</challenge>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SERFMGhNRHg5WFZQS0ZudVBkcU5tOVRpOVFRYmRlTE5BZmVkY1dBWixwPUJBcmo3cFczbE5WZnF5dkN0K1NGV2tpUU5CR3lRTkF5NHkvR3FMVCtuQzg9</response>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj00TmZnL2lUSFBCbDA4VkdOQmc1STV6cGt1VStlekVVd20xZUFGRE1xU0RzPQ==</success>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <iq xmlns="jabber:client" id="iGsziAtgBgNFJGMUV4azoO7" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <iq id="iGsziAtgBgNFJGMUV4azoO7" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-284" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__w7jjsa0532@localhost/943755961-tigase-284</jid></bind></iq>

2020-01-02 19:56:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 >> <iq xmlns="jabber:client" id="FS0DpXbHofEm0akXex8x9HC" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 << <iq id="FS0DpXbHofEm0akXex8x9HC" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-284" type="result"/>

2020-01-02 19:56:56 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 >> <iq to="localhost" id="mXd7jGDl5ocKBqSZeutn3lW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 >> <iq id="iJvFuYfOHsbrhMYiHCgLMAF" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 << <enabled id="ae4b6a07-cb88-41ed-a211-8d52bca00a06" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 << <iq id="mXd7jGDl5ocKBqSZeutn3lW" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-284" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><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 category="pubsub" type="pep"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 << <iq id="iJvFuYfOHsbrhMYiHCgLMAF" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-284" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:56:56 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-284 << <presence xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost" from="jaxmpp__w7jjsa0532@localhost/943755961-tigase-284"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-01-02 19:56:58 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2020-01-02 19:56:58 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-01-02 19:56:58 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-01-02 19:56:58 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2020-01-02 19:56:58 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-01-02 19:56:58 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-01-02 19:57:00 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@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>

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3c3ampzYTA1MzIscj1jUU9Ybm9TOVFralFXdnJESnlMcQ==</auth>

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jUU9Ybm9TOVFralFXdnJESnlMcWwyUHA0a1hxdXZDY1hhM1BIMU9FLHM9cUpQaDZJa2tnM2RERkE9PSxpPTQwOTY=</challenge>

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y1FPWG5vUzlRa2pRV3ZyREp5THFsMlBwNGtYcXV2Q2NYYTNQSDFPRSxwPTRrMkFTcDhaMFB2TDBzaC9XN3Z6KzM1aUVmVW8yK25nUWpEcEphTFE5WFE9</response>

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj14UVNJOFVvV2grK2x3dHVIR2wxY0F6b0xxUEhkUFZKcC9uT3pxUmtIWHJjPQ==</success>

2020-01-02 19:57:00 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@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 node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features>

2020-01-02 19:57:00 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost >> <iq xmlns="jabber:client" id="ZU0NWZcq5qSfV9xkNJJNADq" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost << <iq id="ZU0NWZcq5qSfV9xkNJJNADq" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-285" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__w7jjsa0532@localhost/943755961-tigase-285</jid></bind></iq>

2020-01-02 19:57:01 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 >> <iq xmlns="jabber:client" id="jZdXU7ybq7j1gWezmKJIspf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 << <iq id="jZdXU7ybq7j1gWezmKJIspf" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-285" type="result"/>

2020-01-02 19:57:01 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 >> <iq to="localhost" id="n2k6gNFJGMUV4azoO7FDvgF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 >> <iq id="QTR5tgBgNFJGMUV4azoO7FD" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-01-02 19:57:01 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2020-01-02 19:57:01 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-01-02 19:57:01 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 << <enabled id="41506a4c-3b60-498b-9e2a-e1c24b467f87" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/>

2020-01-02 19:57:01 | jaxmpp_ :: jaxmpp__w7jjsa0532@localhost/943755961-tigase-285 << <iq id="QTR5tgBgNFJGMUV4azoO7FD" xmlns="jabber:client" to="jaxmpp__w7jjsa0532@localhost/943755961-tigase-285" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-01-02 19:57:01 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-01-02 19:57:01 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected