Test Results Report - Testing reconnections

Passed Tests
tigase.tests.jaxmpp.TestReconnections
testMultipleReconnectionsBosh	5.324s	2019-11-27 20:02:19 \| 2019-11-27 20:02:19 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-27 20:02:19 \| ------------------------------------ 2019-11-27 20:02:19 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:19 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:02:19 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:02:19 \| jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:19 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:19 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:19 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzdxZ2VidzA1Mjgscj1meDFwU1MzRU9rZXduT2lDa0ZLVw==</auth> 2019-11-27 20:02:19 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1meDFwU1MzRU9rZXduT2lDa0ZLV1NQZTFPaFdxU0l0ZjBPdUJOcDlqLHM9Ujk4NStsMDhFdi9RTVE9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:19 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZngxcFNTM0VPa2V3bk9pQ2tGS1dTUGUxT2hXcVNJdGYwT3VCTnA5aixwPU16eG55ZjNMWDFjYnhIU1hKME5MZnVWK1lIeTc3eWFQbDJYcGVoaVN0dE09</response> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1KMGk2WENrVlJlaVJtU1pDb3VrQzdBMGZaQklLNUN1UCs5Z3RyYnRwQjNNPQ==</success> 2019-11-27 20:02:20 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <iq xmlns="jabber:client" id="qh1PEHQW8odT9s2k7iQIt2e" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <iq id="qh1PEHQW8odT9s2k7iQIt2e" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__7qgebw0528@localhost/993927746-tigase-280</jid></bind></iq> 2019-11-27 20:02:20 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 >> <iq to="localhost" id="wvFP5DDqYcRMOElMIq3v5Ct" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 >> <iq id="3JjmSS0t0gy9oWSXjHQVX9M" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <iq id="3JjmSS0t0gy9oWSXjHQVX9M" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <iq id="wvFP5DDqYcRMOElMIq3v5Ct" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:20 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <presence to="jaxmpp__7qgebw0528@localhost" xmlns="jabber:client" from="jaxmpp__7qgebw0528@localhost/993927746-tigase-280"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:02:22 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-27 20:02:22 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-27 20:02:22 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:02:22 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:02:24 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzdxZ2VidzA1Mjgscj1Od2ViODZTNFU3U1BmOVNVQ1pYUA==</auth> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1Od2ViODZTNFU3U1BmOVNVQ1pYUFhXSVpUMDRDZUI5dlcybWtKdExhLHM9Ujk4NStsMDhFdi9RTVE9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TndlYjg2UzRVN1NQZjlTVUNaWFBYV0laVDA0Q2VCOXZXMm1rSnRMYSxwPWdzQ1l6RkF2ekZzcGN3cnJvYzdRV0g4cUdaWmNqM1d2RHZyOUY2YmJabUE9</response> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1DOHlaanphUlZ3dStzcXdzOHFiY202MjY1cDlMcWViNmxGWHltQVd5cHh3PQ==</success> 2019-11-27 20:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <iq xmlns="jabber:client" id="NG8ISZ5pi2fxuYl8wiIRbJC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <iq id="NG8ISZ5pi2fxuYl8wiIRbJC" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-281" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__7qgebw0528@localhost/993927746-tigase-281</jid></bind></iq> 2019-11-27 20:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-281 >> <iq to="localhost" id="kxFORcQRLWS9KKKCFNX4gM1" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-281 >> <iq id="TNpDrVgwrkU4gXn7oU4BgMA" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:24 \| jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:25 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200
testMultipleReconnectionsSocket	4.441s	2019-11-27 20:02:25 \| 2019-11-27 20:02:25 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-27 20:02:25 \| ------------------------------------ 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Connector state changed: null->connecting 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] DNS entry stored in session object: localhost:5222 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Preparing connection to [localhost:5222] 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Connector state changed: connecting->connected 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Whitespace ping period is setted to nullms 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3758] Start TLS 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Proceeding TLS 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Start handshake 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3Z6cGp4azA1Mjkscj05RnRXbFlRdzlXSFRHdTc4WmJaag==</auth> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj05RnRXbFlRdzlXSFRHdTc4WmJaam1MS2ZXZXRaa3NqRzFZSEd3ek9BLHM9ZHFIZTJyWWs3clpXeGc9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OUZ0V2xZUXc5V0hUR3U3OFpiWmptTEtmV2V0WmtzakcxWUhHd3pPQSxwPTVKdHBCNjZjT0lLZ3lxZ0NEM1JwV0Jzakk4MVFwaHU1aEVLcCtqanJvQkU9</response> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1NVUk1S2VHcXljUXpZOURvSFdjZTFMc0RpYmRrZVp3Y2w2UEh5Rm8xdnZJPQ==</success> 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <iq xmlns="jabber:client" id="hMeUgLDvuq15AaJU4oUfXhI" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <iq id="hMeUgLDvuq15AaJU4oUfXhI" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vzpjxk0529@localhost/993927746-tigase-282</jid></bind></iq> 2019-11-27 20:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq xmlns="jabber:client" id="Wrb9iZq6s2vtjMGCHsamLCo" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="Wrb9iZq6s2vtjMGCHsamLCo" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"/> 2019-11-27 20:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq to="localhost" id="uRpr8odT9s2k7iQIt2es7Dl" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq id="xysIT9s2k7iQIt2es7Dl8v7" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="uRpr8odT9s2k7iQIt2es7Dl" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <enabled id="38664022-082e-4236-8b60-0e402df62d72" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="xysIT9s2k7iQIt2es7Dl8v7" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:25 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <presence to="jaxmpp__vzpjxk0529@localhost" xmlns="jabber:client" from="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:02:27 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Connector state changed: connected->disconnecting 2019-11-27 20:02:27 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Terminating XMPP Stream 2019-11-27 20:02:27 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:02:27 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:02:27 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Connector state changed: disconnecting->disconnected 2019-11-27 20:02:27 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:02:27 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector state changed: null->connecting 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] DNS entry stored in session object: localhost:5222 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Preparing connection to [localhost:5222] 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Connector state changed: connecting->connected 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=1] Whitespace ping period is setted to nullms 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3758] Start TLS 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Proceeding TLS 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Start handshake 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3Z6cGp4azA1Mjkscj1TYjJQWHdKOU5xZ2xSWHZhTlRwMA==</auth> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1TYjJQWHdKOU5xZ2xSWHZhTlRwMG9sSXp5RFdMVVgxZUNrem5oamFJLHM9ZHFIZTJyWWs3clpXeGc9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9U2IyUFh3SjlOcWdsUlh2YU5UcDBvbEl6eURXTFVYMWVDa3puaGphSSxwPTh0c2pvbWlpWmpVNk9LTmN1dFUvM1Q0QytrZU5vcktYSTQwR05WL2FHNG89</response> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1qb0wyVktPczFoWUhseHFLUW9VakpLMmE3T2dvQnlrakJaczZKRVZnUEZzPQ==</success> 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <iq xmlns="jabber:client" id="HtbMEsU1rXmX1h2wdtmIP6s" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <iq id="HtbMEsU1rXmX1h2wdtmIP6s" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vzpjxk0529@localhost/993927746-tigase-283</jid></bind></iq> 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="nWyV15AaJU4oUfXhIymbq6e" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="nWyV15AaJU4oUfXhIymbq6e" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"/> 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq to="localhost" id="zWwMIymbq6ev6mIDFpgLDvu" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <enabled id="52159885-dd6e-4d84-a718-8ab14ea52686" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq id="6CwFNEy8jIAaxt1udxj6EtZ" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="zWwMIymbq6ev6mIDFpgLDvu" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="6CwFNEy8jIAaxt1udxj6EtZ" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <presence to="jaxmpp__vzpjxk0529@localhost" xmlns="jabber:client" from="jaxmpp__vzpjxk0529@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 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Connector state changed: connected->disconnected 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Stream terminated 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testMultipleReconnectionsWebSocket	4.664s	2019-11-27 20:02:29 \| 2019-11-27 20:02:29 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-27 20:02:29 \| ------------------------------------ 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-27 20:02:29 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:29 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:29 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3dsNWRmazA1MzAscj1MRUpqbWJyNmlEWURkOUNSbHAzQw==</auth> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1MRUpqbWJyNmlEWURkOUNSbHAzQ21GbTZJZVBoSU1JbFJFV0tBdEtlLHM9c0RpWktQN3NkSGhwdHc9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TEVKam1icjZpRFlEZDlDUmxwM0NtRm02SWVQaElNSWxSRVdLQXRLZSxwPXkvb21aU09LaE5MdGhrQkF3U0tSbWJRYndLLzBES05DZGNpSllaNVo4RGM9</response> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1zZ3lVK2d5SlE0akhNTVZNVXE0SnplL0IwZ1Bic3dGRTBCZndEMDdxRzhZPQ==</success> 2019-11-27 20:02:30 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <iq xmlns="jabber:client" id="31BW2cELAqSNGw2hXZq5hN4" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <iq id="31BW2cELAqSNGw2hXZq5hN4" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__wl5dfk0530@localhost/993927746-tigase-284</jid></bind></iq> 2019-11-27 20:02:30 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="DPZOGCgzeCeFJxwhFzCGx7E" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="DPZOGCgzeCeFJxwhFzCGx7E" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"/> 2019-11-27 20:02:30 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq to="localhost" id="bCk4eCeFJxwhFzCGx7EiZq6" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq id="y2yWfEq6Fo26fAkEvpTeHvf" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <enabled id="37bb4e37-a131-48a0-b68a-b5f7ea684107" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="y2yWfEq6Fo26fAkEvpTeHvf" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <presence to="jaxmpp__wl5dfk0530@localhost" xmlns="jabber:client" from="jaxmpp__wl5dfk0530@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 20:02:30 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="bCk4eCeFJxwhFzCGx7EiZq6" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:32 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-27 20:02:32 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-27 20:02:32 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:02:32 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-27 20:02:32 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:02:32 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3dsNWRmazA1MzAscj1IeXlPWVZQd25HMnBRYmNzS2VaTA==</auth> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IeXlPWVZQd25HMnBRYmNzS2VaTDhNb1pLcjg2a0VOejY2WmhENEZZLHM9c0RpWktQN3NkSGhwdHc9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SHl5T1lWUHduRzJwUWJjc0tlWkw4TW9aS3I4NmtFTno2NlpoRDRGWSxwPS9jL2VHU2VSbktFRElJdDY4WnBKa1N2UFZHL2JoSEl3cU52dzZGSHEvYUE9</response> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1vTm9TZXd1TW5iV2xiUkthOFEyQmtzZ0ZteUU2OURLOUJEYmlLcVNxY2xRPQ==</success> 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <iq xmlns="jabber:client" id="WFpBcN2kUWj5xeAqjGCgzeC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <iq id="WFpBcN2kUWj5xeAqjGCgzeC" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-285" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__wl5dfk0530@localhost/993927746-tigase-285</jid></bind></iq> 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="A0ojKKXYcqTSUOP3bORPTgY" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 << <iq id="A0ojKKXYcqTSUOP3bORPTgY" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-285" xmlns="jabber:client" type="result"/> 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq to="localhost" id="eOMV3v5Ct3laMJvqQ5CueAB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq id="KPNX7Dl8v7tqiLzy6BEymQ7" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-11-27 20:02:34 \| jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 << <enabled id="f6de19d6-4cc5-4f50-8877-0d39ec4037cd" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:02:34 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:02:34 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

tigase.tests.jaxmpp.TestReconnections

testMultipleReconnectionsBosh

5.324s

2019-11-27 20:02:19 |

2019-11-27 20:02:19 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 20:02:19 | ------------------------------------

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

2019-11-27 20:02:19 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 20:02:19 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-27 20:02:19 | jaxmpp_ :: jaxmpp__7qgebw0528@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"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

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

2019-11-27 20:02:19 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 20:02:19 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzdxZ2VidzA1Mjgscj1meDFwU1MzRU9rZXduT2lDa0ZLVw==</auth>

2019-11-27 20:02:19 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1meDFwU1MzRU9rZXduT2lDa0ZLV1NQZTFPaFdxU0l0ZjBPdUJOcDlqLHM9Ujk4NStsMDhFdi9RTVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:19 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZngxcFNTM0VPa2V3bk9pQ2tGS1dTUGUxT2hXcVNJdGYwT3VCTnA5aixwPU16eG55ZjNMWDFjYnhIU1hKME5MZnVWK1lIeTc3eWFQbDJYcGVoaVN0dE09</response>

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1KMGk2WENrVlJlaVJtU1pDb3VrQzdBMGZaQklLNUN1UCs5Z3RyYnRwQjNNPQ==</success>

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

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@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"/><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 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <iq xmlns="jabber:client" id="qh1PEHQW8odT9s2k7iQIt2e" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <iq id="qh1PEHQW8odT9s2k7iQIt2e" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__7qgebw0528@localhost/993927746-tigase-280</jid></bind></iq>

2019-11-27 20:02:20 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 >> <iq to="localhost" id="wvFP5DDqYcRMOElMIq3v5Ct" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 >> <iq id="3JjmSS0t0gy9oWSXjHQVX9M" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <iq id="3JjmSS0t0gy9oWSXjHQVX9M" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <iq id="wvFP5DDqYcRMOElMIq3v5Ct" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-280" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:20 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-280 << <presence to="jaxmpp__7qgebw0528@localhost" xmlns="jabber:client" from="jaxmpp__7qgebw0528@localhost/993927746-tigase-280"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 20:02:22 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-27 20:02:22 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-11-27 20:02:22 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

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

2019-11-27 20:02:24 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 20:02:24 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@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"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

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

2019-11-27 20:02:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzdxZ2VidzA1Mjgscj1Od2ViODZTNFU3U1BmOVNVQ1pYUA==</auth>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1Od2ViODZTNFU3U1BmOVNVQ1pYUFhXSVpUMDRDZUI5dlcybWtKdExhLHM9Ujk4NStsMDhFdi9RTVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TndlYjg2UzRVN1NQZjlTVUNaWFBYV0laVDA0Q2VCOXZXMm1rSnRMYSxwPWdzQ1l6RkF2ekZzcGN3cnJvYzdRV0g4cUdaWmNqM1d2RHZyOUY2YmJabUE9</response>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1DOHlaanphUlZ3dStzcXdzOHFiY202MjY1cDlMcWViNmxGWHltQVd5cHh3PQ==</success>

2019-11-27 20:02:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@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"/><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 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost >> <iq xmlns="jabber:client" id="NG8ISZ5pi2fxuYl8wiIRbJC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost << <iq id="NG8ISZ5pi2fxuYl8wiIRbJC" to="jaxmpp__7qgebw0528@localhost/993927746-tigase-281" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__7qgebw0528@localhost/993927746-tigase-281</jid></bind></iq>

2019-11-27 20:02:24 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-281 >> <iq to="localhost" id="kxFORcQRLWS9KKKCFNX4gM1" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@localhost/993927746-tigase-281 >> <iq id="TNpDrVgwrkU4gXn7oU4BgMA" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:24 | jaxmpp_ :: jaxmpp__7qgebw0528@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 20:02:25 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

testMultipleReconnectionsSocket

4.441s

2019-11-27 20:02:25 |

2019-11-27 20:02:25 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 20:02:25 | ------------------------------------

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

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Connector state changed: null->connecting

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

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Preparing connection to [localhost:5222]

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Connector state changed: connecting->connected

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Whitespace ping period is setted to nullms

2019-11-27 20:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><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 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3758] Start TLS

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Proceeding TLS

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Start handshake

2019-11-27 20:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

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

2019-11-27 20:02:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3Z6cGp4azA1Mjkscj05RnRXbFlRdzlXSFRHdTc4WmJaag==</auth>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj05RnRXbFlRdzlXSFRHdTc4WmJaam1MS2ZXZXRaa3NqRzFZSEd3ek9BLHM9ZHFIZTJyWWs3clpXeGc9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OUZ0V2xZUXc5V0hUR3U3OFpiWmptTEtmV2V0WmtzakcxWUhHd3pPQSxwPTVKdHBCNjZjT0lLZ3lxZ0NEM1JwV0Jzakk4MVFwaHU1aEVLcCtqanJvQkU9</response>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1NVUk1S2VHcXljUXpZOURvSFdjZTFMc0RpYmRrZVp3Y2w2UEh5Rm8xdnZJPQ==</success>

2019-11-27 20:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><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 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <iq xmlns="jabber:client" id="hMeUgLDvuq15AaJU4oUfXhI" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <iq id="hMeUgLDvuq15AaJU4oUfXhI" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vzpjxk0529@localhost/993927746-tigase-282</jid></bind></iq>

2019-11-27 20:02:25 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq xmlns="jabber:client" id="Wrb9iZq6s2vtjMGCHsamLCo" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="Wrb9iZq6s2vtjMGCHsamLCo" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"/>

2019-11-27 20:02:25 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq to="localhost" id="uRpr8odT9s2k7iQIt2es7Dl" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 >> <iq id="xysIT9s2k7iQIt2es7Dl8v7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="uRpr8odT9s2k7iQIt2es7Dl" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <enabled id="38664022-082e-4236-8b60-0e402df62d72" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <iq id="xysIT9s2k7iQIt2es7Dl8v7" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:25 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-282 << <presence to="jaxmpp__vzpjxk0529@localhost" xmlns="jabber:client" from="jaxmpp__vzpjxk0529@localhost/993927746-tigase-282"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

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

2019-11-27 20:02:27 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=1] Terminating XMPP Stream

2019-11-27 20:02:27 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 20:02:27 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 20:02:27 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=586 thread=3760] Connector state changed: disconnecting->disconnected

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

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

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

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

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

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

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

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

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

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

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><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 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3758] Start TLS

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Proceeding TLS

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Start handshake

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

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

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3Z6cGp4azA1Mjkscj1TYjJQWHdKOU5xZ2xSWHZhTlRwMA==</auth>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1TYjJQWHdKOU5xZ2xSWHZhTlRwMG9sSXp5RFdMVVgxZUNrem5oamFJLHM9ZHFIZTJyWWs3clpXeGc9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9U2IyUFh3SjlOcWdsUlh2YU5UcDBvbEl6eURXTFVYMWVDa3puaGphSSxwPTh0c2pvbWlpWmpVNk9LTmN1dFUvM1Q0QytrZU5vcktYSTQwR05WL2FHNG89</response>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1qb0wyVktPczFoWUhseHFLUW9VakpLMmE3T2dvQnlrakJaczZKRVZnUEZzPQ==</success>

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@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"/><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 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost >> <iq xmlns="jabber:client" id="HtbMEsU1rXmX1h2wdtmIP6s" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost << <iq id="HtbMEsU1rXmX1h2wdtmIP6s" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__vzpjxk0529@localhost/993927746-tigase-283</jid></bind></iq>

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="nWyV15AaJU4oUfXhIymbq6e" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="nWyV15AaJU4oUfXhIymbq6e" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"/>

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq to="localhost" id="zWwMIymbq6ev6mIDFpgLDvu" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <enabled id="52159885-dd6e-4d84-a718-8ab14ea52686" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 >> <iq id="6CwFNEy8jIAaxt1udxj6EtZ" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="zWwMIymbq6ev6mIDFpgLDvu" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@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 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <iq id="6CwFNEy8jIAaxt1udxj6EtZ" to="jaxmpp__vzpjxk0529@localhost/993927746-tigase-283" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__vzpjxk0529@localhost/993927746-tigase-283 << <presence to="jaxmpp__vzpjxk0529@localhost" xmlns="jabber:client" from="jaxmpp__vzpjxk0529@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 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Connector state changed: connected->disconnected

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=587 thread=3765] Stream terminated

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testMultipleReconnectionsWebSocket

4.664s

2019-11-27 20:02:29 |

2019-11-27 20:02:29 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-27 20:02:29 | ------------------------------------

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

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

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

2019-11-27 20:02:29 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

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

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

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

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__wl5dfk0530@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"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

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

2019-11-27 20:02:29 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-27 20:02:29 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3dsNWRmazA1MzAscj1MRUpqbWJyNmlEWURkOUNSbHAzQw==</auth>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1MRUpqbWJyNmlEWURkOUNSbHAzQ21GbTZJZVBoSU1JbFJFV0tBdEtlLHM9c0RpWktQN3NkSGhwdHc9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TEVKam1icjZpRFlEZDlDUmxwM0NtRm02SWVQaElNSWxSRVdLQXRLZSxwPXkvb21aU09LaE5MdGhrQkF3U0tSbWJRYndLLzBES05DZGNpSllaNVo4RGM9</response>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1zZ3lVK2d5SlE0akhNTVZNVXE0SnplL0IwZ1Bic3dGRTBCZndEMDdxRzhZPQ==</success>

2019-11-27 20:02:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@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"/><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 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <iq xmlns="jabber:client" id="31BW2cELAqSNGw2hXZq5hN4" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <iq id="31BW2cELAqSNGw2hXZq5hN4" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__wl5dfk0530@localhost/993927746-tigase-284</jid></bind></iq>

2019-11-27 20:02:30 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="DPZOGCgzeCeFJxwhFzCGx7E" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="DPZOGCgzeCeFJxwhFzCGx7E" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"/>

2019-11-27 20:02:30 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq to="localhost" id="bCk4eCeFJxwhFzCGx7EiZq6" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 >> <iq id="y2yWfEq6Fo26fAkEvpTeHvf" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <enabled id="37bb4e37-a131-48a0-b68a-b5f7ea684107" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="y2yWfEq6Fo26fAkEvpTeHvf" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <presence to="jaxmpp__wl5dfk0530@localhost" xmlns="jabber:client" from="jaxmpp__wl5dfk0530@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 20:02:30 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-284 << <iq id="bCk4eCeFJxwhFzCGx7EiZq6" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-284" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><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: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 20:02:32 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-27 20:02:32 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-27 20:02:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

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

2019-11-27 20:02:32 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

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

2019-11-27 20:02:34 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-27 20:02:34 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-27 20:02:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

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

2019-11-27 20:02:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

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

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

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

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

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

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

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

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@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"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IeXlPWVZQd25HMnBRYmNzS2VaTDhNb1pLcjg2a0VOejY2WmhENEZZLHM9c0RpWktQN3NkSGhwdHc9PSxpPTQwOTY=</challenge>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SHl5T1lWUHduRzJwUWJjc0tlWkw4TW9aS3I4NmtFTno2NlpoRDRGWSxwPS9jL2VHU2VSbktFRElJdDY4WnBKa1N2UFZHL2JoSEl3cU52dzZGSHEvYUE9</response>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1vTm9TZXd1TW5iV2xiUkthOFEyQmtzZ0ZteUU2OURLOUJEYmlLcVNxY2xRPQ==</success>

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

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@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"/><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 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost >> <iq xmlns="jabber:client" id="WFpBcN2kUWj5xeAqjGCgzeC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost << <iq id="WFpBcN2kUWj5xeAqjGCgzeC" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-285" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__wl5dfk0530@localhost/993927746-tigase-285</jid></bind></iq>

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

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="A0ojKKXYcqTSUOP3bORPTgY" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 << <iq id="A0ojKKXYcqTSUOP3bORPTgY" to="jaxmpp__wl5dfk0530@localhost/993927746-tigase-285" xmlns="jabber:client" type="result"/>

2019-11-27 20:02:34 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq to="localhost" id="eOMV3v5Ct3laMJvqQ5CueAB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 >> <iq id="KPNX7Dl8v7tqiLzy6BEymQ7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@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 20:02:34 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-27 20:02:34 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-27 20:02:34 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2019-11-27 20:02:34 | jaxmpp_ :: jaxmpp__wl5dfk0530@localhost/993927746-tigase-285 << <enabled id="f6de19d6-4cc5-4f50-8877-0d39ec4037cd" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/>

2019-11-27 20:02:34 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

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

Testing reconnections - #4266