Test Results Report - Testing reconnections

Passed Tests
tigase.tests.jaxmpp.TestReconnections
testMultipleReconnectionsBosh	5.341s	2019-03-02 14:19:57 \| 2019-03-02 14:19:57 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-03-02 14:19:57 \| ------------------------------------ 2019-03-02 14:19:57 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:19:57 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:19:57 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:19:57 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3I2OW8xMDQ4MixyPVlMSnVpdmRjWlkzaEMxVHd1dzRy</auth> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ZTEp1aXZkY1pZM2hDMVR3dXc0cnBVUllMMnNDb2pzYzNGMlJaazRMLHM9SWFFSDNBV2c1T1JJL1E9PSxpPTQwOTY=</challenge> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WUxKdWl2ZGNaWTNoQzFUd3V3NHJwVVJZTDJzQ29qc2MzRjJSWms0TCxwPVN5WlQ1alJ5UzMwVlFvSFBnRXFZTXBUZGl5QjRuZ1l0aDFRZGZUZmNJZWc9</response> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1IN2ZkSHNLaW1FTFJORytCMWJQZVoxT1FJT0Y3S3dzUDRNQjFXREtvTnZzPQ==</success> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <iq xmlns="jabber:client" id="YHhmbQ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="YHhmbQ" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__r69o10482@localhost/422068608-tigase-227</jid></bind></iq> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <iq to="localhost" id="W9BPGF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <iq id="n9z0mb" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:19:57 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:19:58 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="n9z0mb" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:19:58 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <presence to="jaxmpp__r69o10482@localhost" xmlns="jabber:client" from="jaxmpp__r69o10482@localhost/422068608-tigase-227"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-03-02 14:19:58 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="W9BPGF" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:00 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-03-02 14:20:00 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-03-02 14:20:00 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-03-02 14:20:00 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3I2OW8xMDQ4MixyPUJyckhBSkFzQVpoOUhRZFlIZ3lL</auth> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CcnJIQUpBc0FaaDlIUWRZSGd5S3I0Um9XZGZwWENyNk51YVFvZm5LLHM9SWFFSDNBV2c1T1JJL1E9PSxpPTQwOTY=</challenge> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QnJySEFKQXNBWmg5SFFkWUhneUtyNFJvV2RmcFhDcjZOdWFRb2ZuSyxwPTN5QW1PNWx0Skd6L2JPRGtnbkx0S2dNSWJFcFgxOHliS2l2SEVnVm9UaVE9</response> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0ybkw3Z1N0clE3Qi9USGRGS3JjZmJlNFptN3hkTFp3TlJyTUtOSXg4MVY4PQ==</success> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost >> <iq xmlns="jabber:client" id="ldJl8D" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-228" id="ldJl8D" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__r69o10482@localhost/422068608-tigase-228</jid></bind></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <iq to="localhost" id="p5jAiC" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <iq id="Dbz5bu" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200
testMultipleReconnectionsSocket	4.234s	2019-03-02 14:20:02 \| 2019-03-02 14:20:02 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-03-02 14:20:02 \| ------------------------------------ 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Start connector (oid=964829290). 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: null->connecting 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: DNS entry stored in session object: localhost:5222 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Preparing connection to [localhost:5222] 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.createSocket: Opening connection to localhost/127.0.0.1:5222 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: connecting->connected 2019-03-02 14:20:02 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Whitespace ping period is setted to nullms 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRoeTVqaDA0ODMscj1aQkpxaG9Mc1RuQ3NacE1ma0E3aw==</auth> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1aQkpxaG9Mc1RuQ3NacE1ma0E3a3U0UUVPWmNIWVNGNVdFWlJBTTFLLHM9QzdpK0ZqMWMvVWFyRFE9PSxpPTQwOTY=</challenge> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WkJKcWhvTHNUbkNzWnBNZmtBN2t1NFFFT1pjSFlTRjVXRVpSQU0xSyxwPVJUT3cyNnZTOWZpZUxNVUU3TXB4L2pKY3VYelU3SmdQa2dTaEQ3S0xnRHc9</response> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Yd1pWWTZhVE81STQzRmFVTTFUZXczclQ1dlA4ZzBmVWpIRTFOREdZUEFjPQ==</success> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@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></features> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <iq xmlns="jabber:client" id="A46Wm9" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="A46Wm9" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hy5jh0483@localhost/422068608-tigase-229</jid></bind></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq xmlns="jabber:client" id="3amtbQ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="3amtbQ" xmlns="jabber:client" type="result"/> 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq to="localhost" id="EiIW0p" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="EiIW0p" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-03-02 14:20:02 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processStreamManagementEnabled: Stream management is enabled. id=52351633-aa85-4f9b-aed1-54a32e0ce79e; resume=true 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <enabled resume="true" location="atlantiscity.local" max="60" id="52351633-aa85-4f9b-aed1-54a32e0ce79e" xmlns="urn:xmpp:sm:3"/> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq id="9Sqtl3" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="9Sqtl3" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:02 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <presence to="jaxmpp__4hy5jh0483@localhost" xmlns="jabber:client" from="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-03-02 14:20:04 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: connected->disconnecting 2019-03-02 14:20:04 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.terminateStream: Terminating XMPP Stream 2019-03-02 14:20:04 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-03-02 14:20:04 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-03-02 14:20:04 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: disconnecting->disconnected 2019-03-02 14:20:05 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Start connector (oid=1050653829). 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: null->connecting 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: DNS entry stored in session object: localhost:5222 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Preparing connection to [localhost:5222] 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.createSocket: Opening connection to localhost/127.0.0.1:5222 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: connecting->connected 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Whitespace ping period is setted to nullms 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRoeTVqaDA0ODMscj1CR3BvNnVYa0paMHl4UzF1U1F6Zg==</auth> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CR3BvNnVYa0paMHl4UzF1U1F6Zlk2S0xtNnhDUEJiQ2Fhb2oxVmpDLHM9QzdpK0ZqMWMvVWFyRFE9PSxpPTQwOTY=</challenge> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QkdwbzZ1WGtKWjB5eFMxdVNRemZZNktMbTZ4Q1BCYkNhYW9qMVZqQyxwPXRBUVRETzA4NFUrek93bFVVQVVQU2s0cFY4empRdS81QmRmUE9XSW96b009</response> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1QQ20yMmtDYzJSa3BnY3Y5RXZ0ejNFcFJaVEVENVhRVkZEeWord045R1c0PQ==</success> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@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></features> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <iq xmlns="jabber:client" id="sUuM1w" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="sUuM1w" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hy5jh0483@localhost/422068608-tigase-230</jid></bind></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq xmlns="jabber:client" id="WmOfwl" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="WmOfwl" xmlns="jabber:client" type="result"/> 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq to="localhost" id="r2O9BF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="r2O9BF" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processStreamManagementEnabled: Stream management is enabled. id=1b3aacb7-bf4a-453c-a097-8517650e071a; resume=true 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <enabled resume="true" location="atlantiscity.local" max="60" id="1b3aacb7-bf4a-453c-a097-8517650e071a" xmlns="urn:xmpp:sm:3"/> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq id="3gsFzC" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="3gsFzC" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <r xmlns="urn:xmpp:sm:3"/> 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processAckAnswer: Current h=2; received h=2 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <a h="2" xmlns="urn:xmpp:sm:3"/> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <presence to="jaxmpp__4hy5jh0483@localhost" xmlns="jabber:client" from="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: connected->disconnected 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.onStreamTerminate: Stream terminated 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testMultipleReconnectionsWebSocket	4.225s	2019-03-02 14:20:07 \| 2019-03-02 14:20:07 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-03-02 14:20:07 \| ------------------------------------ 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-03-02 14:20:07 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:20:07 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFqczc0MTA0ODQscj05OWNuc1VmdWJQa2Vqcnpyb0h6Ng==</auth> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj05OWNuc1VmdWJQa2Vqcnpyb0h6NnVTbDh3b08yelZUUERSZ2ppSVVGLHM9UXdRcFRJSmJRc1duWkE9PSxpPTQwOTY=</challenge> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OTljbnNVZnViUGtlanJ6cm9IejZ1U2w4d29PMnpWVFBEUmdqaUlVRixwPTF2dUVxek9BWHBpUW5WZmdZVUdLWnZVNmhtejhycG1GazdkWVNIR05zSzg9</response> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1GeU1VSHRXL29xcDVVSUszODdTK0RLTE50MEhQZEMvL3VXYXN3Yk5qMmowPQ==</success> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <iq xmlns="jabber:client" id="ghnn7z" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="ghnn7z" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1js7410484@localhost/422068608-tigase-231</jid></bind></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq xmlns="jabber:client" id="utVPWg" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="utVPWg" xmlns="jabber:client" type="result"/> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq to="localhost" id="F2BO1p" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq id="5hiyxw" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="5hiyxw" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <presence to="jaxmpp__1js7410484@localhost" xmlns="jabber:client" from="jaxmpp__1js7410484@localhost/422068608-tigase-231"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-03-02 14:20:07 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="F2BO1p" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:09 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-03-02 14:20:09 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-03-02 14:20:09 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-03-02 14:20:09 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-03-02 14:20:09 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties! 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:11 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFqczc0MTA0ODQscj1RUWd0YVNDZFVWVUo1d1FUODdxTA==</auth> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1RUWd0YVNDZFVWVUo1d1FUODdxTFZoa1k1YnhlMUNGYzVKVkRINXRjLHM9UXdRcFRJSmJRc1duWkE9PSxpPTQwOTY=</challenge> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9UVFndGFTQ2RVVlVKNXdRVDg3cUxWaGtZNWJ4ZTFDRmM1SlZESDV0YyxwPWlLTUI3ZkgxYm16YnQvVkozT2RlRDRIdm5idXVaK2JyTFRiV2d0WDNOZXM9</response> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Pc2E3RWlzM2R6bHlSRWF2czYrcVB1RmNUeHM3OHhwc0ZNREJ4c29DZUljPQ==</success> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost >> <iq xmlns="jabber:client" id="L7MkMV" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="L7MkMV" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1js7410484@localhost/422068608-tigase-232</jid></bind></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq xmlns="jabber:client" id="4kntqh" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="4kntqh" xmlns="jabber:client" type="result"/> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq to="localhost" id="F6FWhV" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq id="xuN9Dw" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="xuN9Dw" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <presence to="jaxmpp__1js7410484@localhost" xmlns="jabber:client" from="jaxmpp__1js7410484@localhost/422068608-tigase-232"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-03-02 14:20:11 \| jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="F6FWhV" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-03-02 14:20:11 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-03-02 14:20:11 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

tigase.tests.jaxmpp.TestReconnections

testMultipleReconnectionsBosh

5.341s

2019-03-02 14:19:57 |

2019-03-02 14:19:57 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-03-02 14:19:57 | ------------------------------------

2019-03-02 14:19:57 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:19:57 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:19:57 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-03-02 14:19:57 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3I2OW8xMDQ4MixyPVlMSnVpdmRjWlkzaEMxVHd1dzRy</auth>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ZTEp1aXZkY1pZM2hDMVR3dXc0cnBVUllMMnNDb2pzYzNGMlJaazRMLHM9SWFFSDNBV2c1T1JJL1E9PSxpPTQwOTY=</challenge>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WUxKdWl2ZGNaWTNoQzFUd3V3NHJwVVJZTDJzQ29qc2MzRjJSWms0TCxwPVN5WlQ1alJ5UzMwVlFvSFBnRXFZTXBUZGl5QjRuZ1l0aDFRZGZUZmNJZWc9</response>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1IN2ZkSHNLaW1FTFJORytCMWJQZVoxT1FJT0Y3S3dzUDRNQjFXREtvTnZzPQ==</success>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <iq xmlns="jabber:client" id="YHhmbQ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="YHhmbQ" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__r69o10482@localhost/422068608-tigase-227</jid></bind></iq>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <iq to="localhost" id="W9BPGF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <iq id="n9z0mb" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:19:57 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:19:58 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="n9z0mb" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:19:58 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <presence to="jaxmpp__r69o10482@localhost" xmlns="jabber:client" from="jaxmpp__r69o10482@localhost/422068608-tigase-227"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-03-02 14:19:58 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-227 << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-227" id="W9BPGF" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:00 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-03-02 14:20:00 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-03-02 14:20:00 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-03-02 14:20:00 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3I2OW8xMDQ4MixyPUJyckhBSkFzQVpoOUhRZFlIZ3lL</auth>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CcnJIQUpBc0FaaDlIUWRZSGd5S3I0Um9XZGZwWENyNk51YVFvZm5LLHM9SWFFSDNBV2c1T1JJL1E9PSxpPTQwOTY=</challenge>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QnJySEFKQXNBWmg5SFFkWUhneUtyNFJvV2RmcFhDcjZOdWFRb2ZuSyxwPTN5QW1PNWx0Skd6L2JPRGtnbkx0S2dNSWJFcFgxOHliS2l2SEVnVm9UaVE9</response>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0ybkw3Z1N0clE3Qi9USGRGS3JjZmJlNFptN3hkTFp3TlJyTUtOSXg4MVY4PQ==</success>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost >> <iq xmlns="jabber:client" id="ldJl8D" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost << <iq to="jaxmpp__r69o10482@localhost/422068608-tigase-228" id="ldJl8D" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__r69o10482@localhost/422068608-tigase-228</jid></bind></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <iq to="localhost" id="p5jAiC" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <iq id="Dbz5bu" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__r69o10482@localhost/422068608-tigase-228 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

testMultipleReconnectionsSocket

4.234s

2019-03-02 14:20:02 |

2019-03-02 14:20:02 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-03-02 14:20:02 | ------------------------------------

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Start connector (oid=964829290).

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: null->connecting

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: DNS entry stored in session object: localhost:5222

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Preparing connection to [localhost:5222]

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.createSocket: Opening connection to localhost/127.0.0.1:5222

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: connecting->connected

2019-03-02 14:20:02 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Whitespace ping period is setted to nullms

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRoeTVqaDA0ODMscj1aQkpxaG9Mc1RuQ3NacE1ma0E3aw==</auth>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1aQkpxaG9Mc1RuQ3NacE1ma0E3a3U0UUVPWmNIWVNGNVdFWlJBTTFLLHM9QzdpK0ZqMWMvVWFyRFE9PSxpPTQwOTY=</challenge>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WkJKcWhvTHNUbkNzWnBNZmtBN2t1NFFFT1pjSFlTRjVXRVpSQU0xSyxwPVJUT3cyNnZTOWZpZUxNVUU3TXB4L2pKY3VYelU3SmdQa2dTaEQ3S0xnRHc9</response>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Yd1pWWTZhVE81STQzRmFVTTFUZXczclQ1dlA4ZzBmVWpIRTFOREdZUEFjPQ==</success>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@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></features>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <iq xmlns="jabber:client" id="A46Wm9" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="A46Wm9" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hy5jh0483@localhost/422068608-tigase-229</jid></bind></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq xmlns="jabber:client" id="3amtbQ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="3amtbQ" xmlns="jabber:client" type="result"/>

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq to="localhost" id="EiIW0p" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="EiIW0p" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-03-02 14:20:02 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processStreamManagementEnabled: Stream management is enabled. id=52351633-aa85-4f9b-aed1-54a32e0ce79e; resume=true

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <enabled resume="true" location="atlantiscity.local" max="60" id="52351633-aa85-4f9b-aed1-54a32e0ce79e" xmlns="urn:xmpp:sm:3"/>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <iq id="9Sqtl3" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229" id="9Sqtl3" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:02 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-229 << <presence to="jaxmpp__4hy5jh0483@localhost" xmlns="jabber:client" from="jaxmpp__4hy5jh0483@localhost/422068608-tigase-229"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-03-02 14:20:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: connected->disconnecting

2019-03-02 14:20:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.terminateStream: Terminating XMPP Stream

2019-03-02 14:20:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-03-02 14:20:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-03-02 14:20:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=964829290) state changed: disconnecting->disconnected

2019-03-02 14:20:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Start connector (oid=1050653829).

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: null->connecting

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: DNS entry stored in session object: localhost:5222

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Preparing connection to [localhost:5222]

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.createSocket: Opening connection to localhost/127.0.0.1:5222

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: connecting->connected

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.start: Whitespace ping period is setted to nullms

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRoeTVqaDA0ODMscj1CR3BvNnVYa0paMHl4UzF1U1F6Zg==</auth>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CR3BvNnVYa0paMHl4UzF1U1F6Zlk2S0xtNnhDUEJiQ2Fhb2oxVmpDLHM9QzdpK0ZqMWMvVWFyRFE9PSxpPTQwOTY=</challenge>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QkdwbzZ1WGtKWjB5eFMxdVNRemZZNktMbTZ4Q1BCYkNhYW9qMVZqQyxwPXRBUVRETzA4NFUrek93bFVVQVVQU2s0cFY4empRdS81QmRmUE9XSW96b009</response>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1QQ20yMmtDYzJSa3BnY3Y5RXZ0ejNFcFJaVEVENVhRVkZEeWord045R1c0PQ==</success>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@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></features>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost >> <iq xmlns="jabber:client" id="sUuM1w" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="sUuM1w" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hy5jh0483@localhost/422068608-tigase-230</jid></bind></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq xmlns="jabber:client" id="WmOfwl" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="WmOfwl" xmlns="jabber:client" type="result"/>

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq to="localhost" id="r2O9BF" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="r2O9BF" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processStreamManagementEnabled: Stream management is enabled. id=1b3aacb7-bf4a-453c-a097-8517650e071a; resume=true

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <enabled resume="true" location="atlantiscity.local" max="60" id="1b3aacb7-bf4a-453c-a097-8517650e071a" xmlns="urn:xmpp:sm:3"/>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <iq id="3gsFzC" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <iq to="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230" id="3gsFzC" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 >> <r xmlns="urn:xmpp:sm:3"/>

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.processAckAnswer: Current h=2; received h=2

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <a h="2" xmlns="urn:xmpp:sm:3"/>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__4hy5jh0483@localhost/422068608-tigase-230 << <presence to="jaxmpp__4hy5jh0483@localhost" xmlns="jabber:client" from="jaxmpp__4hy5jh0483@localhost/422068608-tigase-230"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.setStage: Connector (oid=1050653829) state changed: connected->disconnected

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.onStreamTerminate: Stream terminated

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testMultipleReconnectionsWebSocket

4.225s

2019-03-02 14:20:07 |

2019-03-02 14:20:07 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-03-02 14:20:07 | ------------------------------------

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-03-02 14:20:07 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-03-02 14:20:07 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFqczc0MTA0ODQscj05OWNuc1VmdWJQa2Vqcnpyb0h6Ng==</auth>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj05OWNuc1VmdWJQa2Vqcnpyb0h6NnVTbDh3b08yelZUUERSZ2ppSVVGLHM9UXdRcFRJSmJRc1duWkE9PSxpPTQwOTY=</challenge>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OTljbnNVZnViUGtlanJ6cm9IejZ1U2w4d29PMnpWVFBEUmdqaUlVRixwPTF2dUVxek9BWHBpUW5WZmdZVUdLWnZVNmhtejhycG1GazdkWVNIR05zSzg9</response>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1GeU1VSHRXL29xcDVVSUszODdTK0RLTE50MEhQZEMvL3VXYXN3Yk5qMmowPQ==</success>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <iq xmlns="jabber:client" id="ghnn7z" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="ghnn7z" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1js7410484@localhost/422068608-tigase-231</jid></bind></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq xmlns="jabber:client" id="utVPWg" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="utVPWg" xmlns="jabber:client" type="result"/>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq to="localhost" id="F2BO1p" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <iq id="5hiyxw" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="5hiyxw" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <presence to="jaxmpp__1js7410484@localhost" xmlns="jabber:client" from="jaxmpp__1js7410484@localhost/422068608-tigase-231"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-03-02 14:20:07 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-231 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-231" id="F2BO1p" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:09 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-03-02 14:20:09 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-03-02 14:20:09 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-03-02 14:20:09 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-03-02 14:20:09 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:11 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties!

2019-03-02 14:20:11 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

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

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

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

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

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

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

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><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-03-02 14:20:11 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

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

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFqczc0MTA0ODQscj1RUWd0YVNDZFVWVUo1d1FUODdxTA==</auth>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1RUWd0YVNDZFVWVUo1d1FUODdxTFZoa1k1YnhlMUNGYzVKVkRINXRjLHM9UXdRcFRJSmJRc1duWkE9PSxpPTQwOTY=</challenge>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9UVFndGFTQ2RVVlVKNXdRVDg3cUxWaGtZNWJ4ZTFDRmM1SlZESDV0YyxwPWlLTUI3ZkgxYm16YnQvVkozT2RlRDRIdm5idXVaK2JyTFRiV2d0WDNOZXM9</response>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Pc2E3RWlzM2R6bHlSRWF2czYrcVB1RmNUeHM3OHhwc0ZNREJ4c29DZUljPQ==</success>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <features xmlns="http://etherx.jabber.org/streams"><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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></features>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost >> <iq xmlns="jabber:client" id="L7MkMV" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="L7MkMV" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1js7410484@localhost/422068608-tigase-232</jid></bind></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq xmlns="jabber:client" id="4kntqh" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="4kntqh" xmlns="jabber:client" type="result"/>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq to="localhost" id="F6FWhV" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <iq id="xuN9Dw" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1"/></presence>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="xuN9Dw" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <presence to="jaxmpp__1js7410484@localhost" xmlns="jabber:client" from="jaxmpp__1js7410484@localhost/422068608-tigase-232"><c ver="GDJeU2PgGrtUeUH62yXz3492Sf0=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-03-02 14:20:11 | jaxmpp_ :: jaxmpp__1js7410484@localhost/422068608-tigase-232 << <iq to="jaxmpp__1js7410484@localhost/422068608-tigase-232" id="F6FWhV" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="component" type="im"/><identity name="Tigase ver. 8.0.0-b10083/6923973a" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push: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-03-02 14:20:11 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

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

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

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

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

Testing reconnections - #4266