Test Results Report - Testing reconnections

Passed Tests
tigase.tests.jaxmpp.TestReconnections
testMultipleReconnectionsBosh	5.369s	2019-11-25 20:23:36 \| 2019-11-25 20:23:36 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-25 20:23:36 \| ------------------------------------ 2019-11-25 20:23:36 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:36 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-25 20:23:36 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-25 20:23:36 \| jaxmpp_ :: jaxmpp__1p83ok0527@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-11-25 20:23:36 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:36 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:36 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFwODNvazA1Mjcscj1NdG5JR3dVOVM4bWgyWWFVQUI1VA==</auth> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1NdG5JR3dVOVM4bWgyWWFVQUI1VEZtRERQME1HSk4yMmFKUUxMaDllLHM9RVdXRW5rTTcrRFRRUVE9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TXRuSUd3VTlTOG1oMllhVUFCNVRGbUREUDBNR0pOMjJhSlFMTGg5ZSxwPW1VQ1JmME9TNm00eW5GbDZrT1pFVHZ6ckxXS2ExajVZYjNPM1hpTW5OTk09</response> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0xdmFNc1dLQjdPeXBMUm8zRHNJYmNWRzBXaDJLd1NmV25POVNaaFNTc0VFPQ==</success> 2019-11-25 20:23:37 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <iq xmlns="jabber:client" id="7d1KGvqUYZ8ufVU3BpbJR6h" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" type="result" xmlns="jabber:client" id="7d1KGvqUYZ8ufVU3BpbJR6h"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1p83ok0527@localhost/993927746-tigase-280</jid></bind></iq> 2019-11-25 20:23:37 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 >> <iq to="localhost" id="EuObvqUYZ8ufVU3BpbJR6h0" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 >> <iq id="Pe0CJFHLVexwpdq4l06fJLB" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@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-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" from="localhost" type="result" xmlns="jabber:client" id="EuObvqUYZ8ufVU3BpbJR6h0"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" type="result" xmlns="jabber:client" id="Pe0CJFHLVexwpdq4l06fJLB"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:37 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 << <presence to="jaxmpp__1p83ok0527@localhost" from="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" xmlns="jabber:client"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-25 20:23:39 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-25 20:23:39 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-25 20:23:39 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-25 20:23:39 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-25 20:23:41 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:41 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-25 20:23:41 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-25 20:23:41 \| jaxmpp_ :: jaxmpp__1p83ok0527@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-11-25 20:23:41 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:41 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:41 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFwODNvazA1Mjcscj1qWjF6RTZjTWZoOEczYlQwbmgxNg==</auth> 2019-11-25 20:23:41 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1qWjF6RTZjTWZoOEczYlQwbmgxNmhRYWYwZVdTMkx2U1dwVEtkaGhSLHM9RVdXRW5rTTcrRFRRUVE9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:41 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aloxekU2Y01maDhHM2JUMG5oMTZoUWFmMGVXUzJMdlNXcFRLZGhoUixwPVNRYkZSRmthU00zQkoydXpwWjBhRHpYZDNVeVdhKy9sVm1sRkpHNjlqbVU9</response> 2019-11-25 20:23:41 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj02d2dTMjdoZXYwOU5iUlNzK2doT0JUenlGanY2bVdobGppM0FlS0hZdTg4PQ==</success> 2019-11-25 20:23:41 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <iq xmlns="jabber:client" id="If1zbwgN16rnXhVfSP6zx4m" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="If1zbwgN16rnXhVfSP6zx4m"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1p83ok0527@localhost/993927746-tigase-281</jid></bind></iq> 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 >> <iq to="localhost" id="VB3RN2rhykR2anZhDBoVgAk" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 >> <iq id="Ph3IV3tj1qViXjCs7ycr7x9" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@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-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" from="localhost" type="result" xmlns="jabber:client" id="VB3RN2rhykR2anZhDBoVgAk"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="Ph3IV3tj1qViXjCs7ycr7x9"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200
testMultipleReconnectionsSocket	4.444s	2019-11-25 20:23:42 \| 2019-11-25 20:23:42 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-25 20:23:42 \| ------------------------------------ 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Connector state changed: null->connecting 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] DNS entry stored in session object: localhost:5222 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Preparing connection to [localhost:5222] 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Connector state changed: connecting->connected 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Whitespace ping period is setted to nullms 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><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"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3749] Start TLS 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Proceeding TLS 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Start handshake 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@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"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QzNXp0cjA1Mjgscj1RR2twVjQ3VW5MNExucmR6aXlaOQ==</auth> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1RR2twVjQ3VW5MNExucmR6aXlaOVd0RzJ1Um9YYnhtQzVpU1JZdVJpLHM9c0pxTGlwd3JiVWFISnc9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9UUdrcFY0N1VuTDRMbnJkeml5WjlXdEcydVJvWGJ4bUM1aVNSWXVSaSxwPVFMOTJYRklsN2FJSXBqK24xZzgvZmhqdTFMZ21TM2hlSWpIRW1vcDVKOUU9</response> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1JSWJHWUpJNFJLdUFxZHM1UUxkTDQyQmZURFBjTjBDOWRIalBVNFZneEY4PQ==</success> 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <iq xmlns="jabber:client" id="NyKiExzl3tl4dS7DgKHIw5p" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="NyKiExzl3tl4dS7DgKHIw5p"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__t35ztr0528@localhost/993927746-tigase-282</jid></bind></iq> 2019-11-25 20:23:42 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 >> <iq xmlns="jabber:client" id="Fuwyw6mLGArjGBHBzvpcto9" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="Fuwyw6mLGArjGBHBzvpcto9"/> 2019-11-25 20:23:42 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 >> <iq to="localhost" id="Am6OVVbyfUSURSYdRMN5n7B" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="cb7ccd45-4448-4285-a2a3-0cdd71d2226b"/> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 >> <iq id="7pd8s3w6mLGArjGBHBzvpct" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@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-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" from="localhost" type="result" xmlns="jabber:client" id="Am6OVVbyfUSURSYdRMN5n7B"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="7pd8s3w6mLGArjGBHBzvpct"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:42 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <presence to="jaxmpp__t35ztr0528@localhost" from="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" xmlns="jabber:client"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-25 20:23:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Connector state changed: connected->disconnecting 2019-11-25 20:23:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Terminating XMPP Stream 2019-11-25 20:23:44 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-25 20:23:44 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-25 20:23:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Connector state changed: disconnecting->disconnected 2019-11-25 20:23:44 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-25 20:23:44 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Connector state changed: null->connecting 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] DNS entry stored in session object: localhost:5222 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Preparing connection to [localhost:5222] 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Connector state changed: connecting->connected 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Whitespace ping period is setted to nullms 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><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"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3749] Start TLS 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Proceeding TLS 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Start handshake 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@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"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QzNXp0cjA1Mjgscj1GbmVsWFpDUHY4TFNRSjJsaUI5Uw==</auth> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GbmVsWFpDUHY4TFNRSjJsaUI5U2RDaHFXUlFBcWpyVlo2U1VidGhQLHM9c0pxTGlwd3JiVWFISnc9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Rm5lbFhaQ1B2OExTUUoybGlCOVNkQ2hxV1JRQXFqclZaNlNVYnRoUCxwPWVMdUhqa2FPODRXQ0xZcnFLMFhsNytKc3pNREVYak5NWEc2RFpOT3pwazg9</response> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1RRlArVVNwRWFDUlNud0FNOFEvUTRhekxHSDI5Z3lub2RyUW1yTEZjMFZNPQ==</success> 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <iq xmlns="jabber:client" id="8G0zhY0anU2l7iP9vn7DEnc" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="8G0zhY0anU2l7iP9vn7DEnc"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__t35ztr0528@localhost/993927746-tigase-283</jid></bind></iq> 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="dwuoW6tnORdDDDFIvshXU2h" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="dwuoW6tnORdDDDFIvshXU2h"/> 2019-11-25 20:23:46 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq to="localhost" id="lSwCBzxsZqdPEwfOItbFp9u" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq id="UL94w7GJRIEvkKxjVVbyfUS" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@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-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="1abf7d0e-53a6-468a-83bf-c9b0d377f99c"/> 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Connector state changed: connected->disconnected 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Stream terminated 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" from="localhost" type="result" xmlns="jabber:client" id="lSwCBzxsZqdPEwfOItbFp9u"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-25 20:23:46 \| jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="UL94w7GJRIEvkKxjVVbyfUS"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-25 20:23:46 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testMultipleReconnectionsWebSocket	4.654s	2019-11-25 20:23:47 \| 2019-11-25 20:23:47 \| null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections] 2019-11-25 20:23:47 \| ------------------------------------ 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@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-11-25 20:23:47 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2duZWYzcjA1Mjkscj1aaGd5SVZSUE9LVEpQQmhrZWY4eA==</auth> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1aaGd5SVZSUE9LVEpQQmhrZWY4eEZ4bElXVkI3TTBkRElYSllWRG1HLHM9S2p6eEk0M3BudmtQWXc9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WmhneUlWUlBPS1RKUEJoa2VmOHhGeGxJV1ZCN00wZERJWEpZVkRtRyxwPU1OWEw0MGw2dmlreHpZNWhZOW1uYUwwdjlzdk9pNHFCMXkxdkoydXdTeUU9</response> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1QWVQvT0lFd0N0T1BYUEJIbGRCbXNHMlI5MVR4eEUrWjVkM2NzSkZ3S0NVPQ==</success> 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <iq xmlns="jabber:client" id="VRjnP6zx4mVaqT1lLRUXkIA" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="VRjnP6zx4mVaqT1lLRUXkIA"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__gnef3r0529@localhost/993927746-tigase-284</jid></bind></iq> 2019-11-25 20:23:47 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="fDBNR9mXp1pbLUTeP36q3qW" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="fDBNR9mXp1pbLUTeP36q3qW"/> 2019-11-25 20:23:47 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq to="localhost" id="sWuIPEwfOItbFp9u7EmSNKI" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq id="sXvKTMMLQMBrbrdCnaoWV0e" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@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-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="9a57948d-bd1b-4f1f-859d-bbee84738500"/> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="sXvKTMMLQMBrbrdCnaoWV0e"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" from="localhost" type="result" xmlns="jabber:client" id="sWuIPEwfOItbFp9u7EmSNKI"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-25 20:23:47 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <presence to="jaxmpp__gnef3r0529@localhost" from="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" xmlns="jabber:client"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-25 20:23:49 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-25 20:23:49 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-25 20:23:49 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-25 20:23:49 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-25 20:23:49 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-25 20:23:49 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@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-11-25 20:23:51 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2duZWYzcjA1Mjkscj1JSVR4R2pJQkllb1VDY0Y3OWlGdw==</auth> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1JSVR4R2pJQkllb1VDY0Y3OWlGd1ZpZ1ZONW51TU9YM1FwZVpNcFhBLHM9S2p6eEk0M3BudmtQWXc9PSxpPTQwOTY=</challenge> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SUlUeEdqSUJJZW9VQ2NGNzlpRndWaWdWTjVudU1PWDNRcGVaTXBYQSxwPTUzc3FLRXNjUnRRWWdYNDZGc0tQWVFXYS9Pd0d3bDE3N2U2VUNsS0UxbTQ9</response> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1sTjd3WWM4cGtySkpMWWh4L3MrR3dDUUI4Q0R4V1R2cWRPdG8va2kzVjNJPQ==</success> 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <iq xmlns="jabber:client" id="zCskT5fPQP4cy9s8xv4nfHL" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="zCskT5fPQP4cy9s8xv4nfHL"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__gnef3r0529@localhost/993927746-tigase-285</jid></bind></iq> 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="CQ6EDs3w6mLGArjGBHBzvpc" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="CQ6EDs3w6mLGArjGBHBzvpc"/> 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq to="localhost" id="SLPo9HMDINZYaxoSNPXZjOL" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq id="PDpqlT9FJQLYj12iNUURXdv" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-25 20:23:51 \| jaxmpp_ :: jaxmpp__gnef3r0529@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-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-11-25 20:23:51 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-25 20:23:51 \| tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-25 20:23:52 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

tigase.tests.jaxmpp.TestReconnections

testMultipleReconnectionsBosh

5.369s

2019-11-25 20:23:36 |

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

2019-11-25 20:23:36 | ------------------------------------

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

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

2019-11-25 20:23:36 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-25 20:23:36 | jaxmpp_ :: jaxmpp__1p83ok0527@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-11-25 20:23:36 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

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

2019-11-25 20:23:36 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFwODNvazA1Mjcscj1NdG5JR3dVOVM4bWgyWWFVQUI1VA==</auth>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1NdG5JR3dVOVM4bWgyWWFVQUI1VEZtRERQME1HSk4yMmFKUUxMaDllLHM9RVdXRW5rTTcrRFRRUVE9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TXRuSUd3VTlTOG1oMllhVUFCNVRGbUREUDBNR0pOMjJhSlFMTGg5ZSxwPW1VQ1JmME9TNm00eW5GbDZrT1pFVHZ6ckxXS2ExajVZYjNPM1hpTW5OTk09</response>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0xdmFNc1dLQjdPeXBMUm8zRHNJYmNWRzBXaDJLd1NmV25POVNaaFNTc0VFPQ==</success>

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

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <iq xmlns="jabber:client" id="7d1KGvqUYZ8ufVU3BpbJR6h" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" type="result" xmlns="jabber:client" id="7d1KGvqUYZ8ufVU3BpbJR6h"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1p83ok0527@localhost/993927746-tigase-280</jid></bind></iq>

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

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 >> <iq to="localhost" id="EuObvqUYZ8ufVU3BpbJR6h0" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 >> <iq id="Pe0CJFHLVexwpdq4l06fJLB" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@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-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" from="localhost" type="result" xmlns="jabber:client" id="EuObvqUYZ8ufVU3BpbJR6h0"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2019-11-25 20:23:37 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-280 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-280" type="result" xmlns="jabber:client" id="Pe0CJFHLVexwpdq4l06fJLB"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-25 20:23:39 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-25 20:23:39 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-11-25 20:23:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

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

2019-11-25 20:23:41 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-25 20:23:41 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-25 20:23:41 | jaxmpp_ :: jaxmpp__1p83ok0527@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-11-25 20:23:41 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

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

2019-11-25 20:23:41 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFwODNvazA1Mjcscj1qWjF6RTZjTWZoOEczYlQwbmgxNg==</auth>

2019-11-25 20:23:41 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1qWjF6RTZjTWZoOEczYlQwbmgxNmhRYWYwZVdTMkx2U1dwVEtkaGhSLHM9RVdXRW5rTTcrRFRRUVE9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:41 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aloxekU2Y01maDhHM2JUMG5oMTZoUWFmMGVXUzJMdlNXcFRLZGhoUixwPVNRYkZSRmthU00zQkoydXpwWjBhRHpYZDNVeVdhKy9sVm1sRkpHNjlqbVU9</response>

2019-11-25 20:23:41 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj02d2dTMjdoZXYwOU5iUlNzK2doT0JUenlGanY2bVdobGppM0FlS0hZdTg4PQ==</success>

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost >> <iq xmlns="jabber:client" id="If1zbwgN16rnXhVfSP6zx4m" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="If1zbwgN16rnXhVfSP6zx4m"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1p83ok0527@localhost/993927746-tigase-281</jid></bind></iq>

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 >> <iq to="localhost" id="VB3RN2rhykR2anZhDBoVgAk" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 >> <iq id="Ph3IV3tj1qViXjCs7ycr7x9" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@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-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" from="localhost" type="result" xmlns="jabber:client" id="VB3RN2rhykR2anZhDBoVgAk"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__1p83ok0527@localhost/993927746-tigase-281 << <iq to="jaxmpp__1p83ok0527@localhost/993927746-tigase-281" type="result" xmlns="jabber:client" id="Ph3IV3tj1qViXjCs7ycr7x9"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:42 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

testMultipleReconnectionsSocket

4.444s

2019-11-25 20:23:42 |

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

2019-11-25 20:23:42 | ------------------------------------

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

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

2019-11-25 20:23:42 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

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

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

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

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

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

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

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><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-25 20:23:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3749] Start TLS

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

2019-11-25 20:23:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Proceeding TLS

2019-11-25 20:23:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Start handshake

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

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

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

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QzNXp0cjA1Mjgscj1RR2twVjQ3VW5MNExucmR6aXlaOQ==</auth>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1RR2twVjQ3VW5MNExucmR6aXlaOVd0RzJ1Um9YYnhtQzVpU1JZdVJpLHM9c0pxTGlwd3JiVWFISnc9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9UUdrcFY0N1VuTDRMbnJkeml5WjlXdEcydVJvWGJ4bUM1aVNSWXVSaSxwPVFMOTJYRklsN2FJSXBqK24xZzgvZmhqdTFMZ21TM2hlSWpIRW1vcDVKOUU9</response>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1JSWJHWUpJNFJLdUFxZHM1UUxkTDQyQmZURFBjTjBDOWRIalBVNFZneEY4PQ==</success>

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <iq xmlns="jabber:client" id="NyKiExzl3tl4dS7DgKHIw5p" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

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

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

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="Fuwyw6mLGArjGBHBzvpcto9"/>

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

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

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

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="cb7ccd45-4448-4285-a2a3-0cdd71d2226b"/>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 >> <iq id="7pd8s3w6mLGArjGBHBzvpct" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@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-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" from="localhost" type="result" xmlns="jabber:client" id="Am6OVVbyfUSURSYdRMN5n7B"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2019-11-25 20:23:42 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-282 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-282" type="result" xmlns="jabber:client" id="7pd8s3w6mLGArjGBHBzvpct"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-25 20:23:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Connector state changed: connected->disconnecting

2019-11-25 20:23:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Terminating XMPP Stream

2019-11-25 20:23:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-25 20:23:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-25 20:23:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3751] Connector state changed: disconnecting->disconnected

2019-11-25 20:23:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

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

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

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

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

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

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

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

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

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

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><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-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3749] Start TLS

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

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Proceeding TLS

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Start handshake

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

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

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

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

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3QzNXp0cjA1Mjgscj1GbmVsWFpDUHY4TFNRSjJsaUI5Uw==</auth>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GbmVsWFpDUHY4TFNRSjJsaUI5U2RDaHFXUlFBcWpyVlo2U1VidGhQLHM9c0pxTGlwd3JiVWFISnc9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Rm5lbFhaQ1B2OExTUUoybGlCOVNkQ2hxV1JRQXFqclZaNlNVYnRoUCxwPWVMdUhqa2FPODRXQ0xZcnFLMFhsNytKc3pNREVYak5NWEc2RFpOT3pwazg9</response>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1RRlArVVNwRWFDUlNud0FNOFEvUTRhekxHSDI5Z3lub2RyUW1yTEZjMFZNPQ==</success>

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

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost >> <iq xmlns="jabber:client" id="8G0zhY0anU2l7iP9vn7DEnc" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="8G0zhY0anU2l7iP9vn7DEnc"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__t35ztr0528@localhost/993927746-tigase-283</jid></bind></iq>

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

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="dwuoW6tnORdDDDFIvshXU2h" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="dwuoW6tnORdDDDFIvshXU2h"/>

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

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq to="localhost" id="lSwCBzxsZqdPEwfOItbFp9u" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 >> <iq id="UL94w7GJRIEvkKxjVVbyfUS" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@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-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="1abf7d0e-53a6-468a-83bf-c9b0d377f99c"/>

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Connector state changed: connected->disconnected

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3756] Stream terminated

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" from="localhost" type="result" xmlns="jabber:client" id="lSwCBzxsZqdPEwfOItbFp9u"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2019-11-25 20:23:46 | jaxmpp_ :: jaxmpp__t35ztr0528@localhost/993927746-tigase-283 << <iq to="jaxmpp__t35ztr0528@localhost/993927746-tigase-283" type="result" xmlns="jabber:client" id="UL94w7GJRIEvkKxjVVbyfUS"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-25 20:23:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testMultipleReconnectionsWebSocket

4.654s

2019-11-25 20:23:47 |

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

2019-11-25 20:23:47 | ------------------------------------

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

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

2019-11-25 20:23:47 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-25 20:23:47 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-25 20:23:47 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-25 20:23:47 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-25 20:23:47 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-25 20:23:47 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-25 20:23:47 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

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

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@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-11-25 20:23:47 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

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

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2duZWYzcjA1Mjkscj1aaGd5SVZSUE9LVEpQQmhrZWY4eA==</auth>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1aaGd5SVZSUE9LVEpQQmhrZWY4eEZ4bElXVkI3TTBkRElYSllWRG1HLHM9S2p6eEk0M3BudmtQWXc9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WmhneUlWUlBPS1RKUEJoa2VmOHhGeGxJV1ZCN00wZERJWEpZVkRtRyxwPU1OWEw0MGw2dmlreHpZNWhZOW1uYUwwdjlzdk9pNHFCMXkxdkoydXdTeUU9</response>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1QWVQvT0lFd0N0T1BYUEJIbGRCbXNHMlI5MVR4eEUrWjVkM2NzSkZ3S0NVPQ==</success>

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

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <iq xmlns="jabber:client" id="VRjnP6zx4mVaqT1lLRUXkIA" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="VRjnP6zx4mVaqT1lLRUXkIA"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__gnef3r0529@localhost/993927746-tigase-284</jid></bind></iq>

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

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="fDBNR9mXp1pbLUTeP36q3qW" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="fDBNR9mXp1pbLUTeP36q3qW"/>

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

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq to="localhost" id="sWuIPEwfOItbFp9u7EmSNKI" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 >> <iq id="sXvKTMMLQMBrbrdCnaoWV0e" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@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-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <enabled max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="9a57948d-bd1b-4f1f-859d-bbee84738500"/>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" type="result" xmlns="jabber:client" id="sXvKTMMLQMBrbrdCnaoWV0e"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:47 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-284 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-284" from="localhost" type="result" xmlns="jabber:client" id="sWuIPEwfOItbFp9u7EmSNKI"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10580/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><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 type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

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

2019-11-25 20:23:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-25 20:23:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-25 20:23:49 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-25 20:23:49 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

2019-11-25 20:23:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

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

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

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

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@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-11-25 20:23:51 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

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

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2duZWYzcjA1Mjkscj1JSVR4R2pJQkllb1VDY0Y3OWlGdw==</auth>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1JSVR4R2pJQkllb1VDY0Y3OWlGd1ZpZ1ZONW51TU9YM1FwZVpNcFhBLHM9S2p6eEk0M3BudmtQWXc9PSxpPTQwOTY=</challenge>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SUlUeEdqSUJJZW9VQ2NGNzlpRndWaWdWTjVudU1PWDNRcGVaTXBYQSxwPTUzc3FLRXNjUnRRWWdYNDZGc0tQWVFXYS9Pd0d3bDE3N2U2VUNsS0UxbTQ9</response>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1sTjd3WWM4cGtySkpMWWh4L3MrR3dDUUI4Q0R4V1R2cWRPdG8va2kzVjNJPQ==</success>

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

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@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="bt2mj6vq9J3s6VE0/7D8Mo0TIoU="/></features>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost >> <iq xmlns="jabber:client" id="zCskT5fPQP4cy9s8xv4nfHL" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="zCskT5fPQP4cy9s8xv4nfHL"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__gnef3r0529@localhost/993927746-tigase-285</jid></bind></iq>

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

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="CQ6EDs3w6mLGArjGBHBzvpc" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 << <iq to="jaxmpp__gnef3r0529@localhost/993927746-tigase-285" type="result" xmlns="jabber:client" id="CQ6EDs3w6mLGArjGBHBzvpc"/>

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

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq to="localhost" id="SLPo9HMDINZYaxoSNPXZjOL" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@localhost/993927746-tigase-285 >> <iq id="PDpqlT9FJQLYj12iNUURXdv" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-25 20:23:51 | jaxmpp_ :: jaxmpp__gnef3r0529@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-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2019-11-25 20:23:51 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-25 20:23:51 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-11-25 20:23:52 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

Testing reconnections - #4266