Testing reconnections - #4266

Test duration: 15.724s

Passed Tests
tigase.tests.jaxmpp.TestReconnections
testMultipleReconnectionsBosh 5.381s
2019-11-26 18:50:20 |

2019-11-26 18:50:20 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-26 18:50:20 | ------------------------------------

2019-11-26 18:50:20 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-26 18:50:20 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-26 18:50:20 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@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-26 18:50:20 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:20 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2R4ZTZoZjA1Mjcscj1HeHMyQWl1dEhDOVpFc1hra040QQ==</auth>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HeHMyQWl1dEhDOVpFc1hra040QXY2SnpuQ3kwUUxIVGpqT0lReTRKLHM9dUd4bWJMdGpQVitwWWc9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9R3hzMkFpdXRIQzlaRXNYa2tONEF2Nkp6bkN5MFFMSFRqak9JUXk0SixwPXVCU2svd0JsRlV6TjdCK0U3ejBPSWRZS2Y3OFVKNDJ6Ti9zVFpmK01hVzg9</response>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj00RlRKT0oxS3MxRDJTMy9XSXdvU3o2T1J1eS9GQWRvc3hKcGJDNE5BSEZjPQ==</success>

2019-11-26 18:50:20 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <iq xmlns="jabber:client" id="IGirYWY2p4peR7CwqT6rbtr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <iq xmlns="jabber:client" to="jaxmpp__dxe6hf0527@localhost/993927746-tigase-281" type="result" id="IGirYWY2p4peR7CwqT6rbtr"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__dxe6hf0527@localhost/993927746-tigase-281</jid></bind></iq>

2019-11-26 18:50:20 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-281 >> <iq to="localhost" id="YxPgPL18FxwpfHMR9M2ycoS" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-281 >> <iq id="1ka6pgTdDEBDKYqWSQMT2l6" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-281 << <iq xmlns="jabber:client" to="jaxmpp__dxe6hf0527@localhost/993927746-tigase-281" type="result" id="1ka6pgTdDEBDKYqWSQMT2l6"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:20 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-281 << <presence from="jaxmpp__dxe6hf0527@localhost/993927746-tigase-281" to="jaxmpp__dxe6hf0527@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-11-26 18:50:22 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-26 18:50:22 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-26 18:50:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-26 18:50:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-26 18:50:24 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-26 18:50:24 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@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-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2R4ZTZoZjA1Mjcscj1oSXNSS3ViZ3BlQ3hzTjZKMTdhUA==</auth>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1oSXNSS3ViZ3BlQ3hzTjZKMTdhUDl5NkVyZzJ4RXY4ZTdtRWZ1M0lLLHM9dUd4bWJMdGpQVitwWWc9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aElzUkt1YmdwZUN4c042SjE3YVA5eTZFcmcyeEV2OGU3bUVmdTNJSyxwPU1KVTNkajIrTGtmWUhvVlhRLzVoeFFPQlhObTNacWM1MXFrYUthVndGSTA9</response>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj05QVZUbGo0WUxvY1ZTQkJYbGNKeXJ6SWhuZHNiVkJZTXFjKzdLWWdyQm1vPQ==</success>

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost >> <iq xmlns="jabber:client" id="5C0OP2nbQaOIqWYhOTcNZm0" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost << <iq xmlns="jabber:client" to="jaxmpp__dxe6hf0527@localhost/993927746-tigase-282" type="result" id="5C0OP2nbQaOIqWYhOTcNZm0"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__dxe6hf0527@localhost/993927746-tigase-282</jid></bind></iq>

2019-11-26 18:50:25 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-282 >> <iq to="localhost" id="xmcaxwpfHMR9M2ycoSQ1iR8" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-282 >> <iq id="PxH2un9GEGp8vjJX0p1ul7r" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__dxe6hf0527@localhost/993927746-tigase-282 << <iq xmlns="jabber:client" to="jaxmpp__dxe6hf0527@localhost/993927746-tigase-282" type="result" id="PxH2un9GEGp8vjJX0p1ul7r"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

testMultipleReconnectionsSocket 4.439s
2019-11-26 18:50:25 |

2019-11-26 18:50:25 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-26 18:50:25 | ------------------------------------

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-26 18:50:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-26 18:50:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

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

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

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

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

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

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

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@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-26 18:50:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3745] Start TLS

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-26 18:50:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3747] Proceeding TLS

2019-11-26 18:50:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3747] Start handshake

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@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-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3M4b2J0bDA1Mjgscj03OWhTUGNXSXRRa2xqMll5QnhheA==</auth>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03OWhTUGNXSXRRa2xqMll5QnhheEZiWEFTeUsyYWkzNUYxdTZiZWFoLHM9NnlhVjg2WmlPQWdSNFE9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NzloU1BjV0l0UWtsajJZeUJ4YXhGYlhBU3lLMmFpMzVGMXU2YmVhaCxwPXZaOWhZK29KREk4d2FpSEZ3N1Rvem0wNDloUEZtU3kxWTVBYlZWUUo3T2c9</response>

2019-11-26 18:50:25 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1VMmpBZ3hVYk1tYWRtdFNROWVzQ055dytSVGU3aVJNZzZ3dDBnMTRzWFl3PQ==</success>

2019-11-26 18:50:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <iq xmlns="jabber:client" id="9EUCIUiGmOILMP4xtkWgzs9" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-283" type="result" id="9EUCIUiGmOILMP4xtkWgzs9"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__s8obtl0528@localhost/993927746-tigase-283</jid></bind></iq>

2019-11-26 18:50:26 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 >> <iq xmlns="jabber:client" id="OH1FBCDFIIOFExnRdwr5CBD" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-283" type="result" id="OH1FBCDFIIOFExnRdwr5CBD"/>

2019-11-26 18:50:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 >> <iq to="localhost" id="yMe2wpfHMR9M2ycoSQ1iR8F" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 >> <iq id="QFRvpdOUcrcDzfZ4xh0nbzw" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@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-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 << <enabled id="10ad98df-152b-4bb9-a782-aa07735f7b3a" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/>

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

2019-11-26 18:50:26 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-283 << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-283" type="result" id="QFRvpdOUcrcDzfZ4xh0nbzw"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-26 18:50:28 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Connector  state changed: connected->disconnecting

2019-11-26 18:50:28 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=1] Terminating XMPP Stream

2019-11-26 18:50:28 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-26 18:50:28 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-26 18:50:28 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=584 thread=3747] Connector  state changed: disconnecting->disconnected

2019-11-26 18:50:28 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-26 18:50:28 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Connector  state changed: null->connecting

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] DNS entry stored in session object: localhost:5222

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Preparing connection to [localhost:5222]

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Connector  state changed: connecting->connected

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=1] Whitespace ping period is setted to nullms

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@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-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3745] Start TLS

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3752] Proceeding TLS

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3752] Start handshake

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@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-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3M4b2J0bDA1Mjgscj10M005MFBiSDJwU2hxVlZUdnhaQQ==</auth>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj10M005MFBiSDJwU2hxVlZUdnhaQTRTYkZaRzFUMnVYTzNQMTh5Zm9ELHM9NnlhVjg2WmlPQWdSNFE9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dDNNOTBQYkgycFNocVZWVHZ4WkE0U2JGWkcxVDJ1WE8zUDE4eWZvRCxwPXQzQnM3VzR1bk42OG1keXhQKzhPOXc0czlJVy8wM1dZREZLdUpGL3ZkU0E9</response>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Gb200QTBYUG93aXU4eVQxK2pUaDBxT29QaElHNlFMYXNkSVVuMTYxU01NPQ==</success>

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost >> <iq xmlns="jabber:client" id="9IYKYqkKu4eNQXk3vo4w5ud" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-284" type="result" id="9IYKYqkKu4eNQXk3vo4w5ud"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__s8obtl0528@localhost/993927746-tigase-284</jid></bind></iq>

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 >> <iq xmlns="jabber:client" id="TPhjUcrcDzfZ4xh0nbzwl7I" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-284" type="result" id="TPhjUcrcDzfZ4xh0nbzwl7I"/>

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 >> <iq to="localhost" id="FK0O6GQMycujS1sj1q8El0f" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 << <enabled id="60dae9b1-1f3f-48b4-b85c-fc4dd1af8a18" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 >> <iq id="lSmun8yu1mXdxzeRViXlTaD" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 << <iq xmlns="jabber:client" to="jaxmpp__s8obtl0528@localhost/993927746-tigase-284" type="result" id="lSmun8yu1mXdxzeRViXlTaD"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__s8obtl0528@localhost/993927746-tigase-284 << <presence from="jaxmpp__s8obtl0528@localhost/993927746-tigase-284" to="jaxmpp__s8obtl0528@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3752] Connector  state changed: connected->disconnected

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=585 thread=3752] Stream terminated

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testMultipleReconnectionsWebSocket 4.605s
2019-11-26 18:50:30 |

2019-11-26 18:50:30 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2019-11-26 18:50:30 | ------------------------------------

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@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-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzMzZmZqbDA1Mjkscj14MnlBRldaakdwQnpSSWRFaGRlMg==</auth>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj14MnlBRldaakdwQnpSSWRFaGRlMlpEOENBTUlueDM2S1pmT25xYVFrLHM9akVOcXNqUlFsVE5TWVE9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eDJ5QUZXWmpHcEJ6UklkRWhkZTJaRDhDQU1JbngzNktaZk9ucWFRayxwPTRWQ3FyTWNYK3RnMW5jYWRLZHdSSTR1aGtIZWpJR2kyVi9ESEhYekVXNkk9</response>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1ZL0VFQkRvSXBRcWI2ZzEraWxyYkJhR3ZiVTJJN21iejFPcVBFcGw2ZnhjPQ==</success>

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <iq xmlns="jabber:client" id="tLTs1xaJFMVhLU7FO6GsUUU" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <iq xmlns="jabber:client" to="jaxmpp__33ffjl0529@localhost/993927746-tigase-285" type="result" id="tLTs1xaJFMVhLU7FO6GsUUU"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__33ffjl0529@localhost/993927746-tigase-285</jid></bind></iq>

2019-11-26 18:50:30 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 >> <iq xmlns="jabber:client" id="QO0NZn8yu1mXdxzeRViXlTa" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 << <iq xmlns="jabber:client" to="jaxmpp__33ffjl0529@localhost/993927746-tigase-285" type="result" id="QO0NZn8yu1mXdxzeRViXlTa"/>

2019-11-26 18:50:30 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 >> <iq to="localhost" id="jOYAAzxscMBwhRP9obzAxvd" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 >> <iq id="rCmp3pVlMX7GJTZm1tqk8mI" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@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-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 << <enabled id="d0b29e4a-4ab0-49f5-bb4f-d84755495c9e" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 << <iq xmlns="jabber:client" to="jaxmpp__33ffjl0529@localhost/993927746-tigase-285" type="result" id="rCmp3pVlMX7GJTZm1tqk8mI"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:30 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-285 << <presence from="jaxmpp__33ffjl0529@localhost/993927746-tigase-285" to="jaxmpp__33ffjl0529@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence>

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

2019-11-26 18:50:32 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-26 18:50:32 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-26 18:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-26 18:50:32 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-26 18:50:32 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-26 18:50:32 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

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

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-26 18:50:34 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-26 18:50:34 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-26 18:50:34 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-26 18:50:34 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:34 | jaxmpp_ :: jaxmpp__33ffjl0529@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-26 18:50:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-26 18:50:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-26 18:50:34 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzMzZmZqbDA1Mjkscj16c21uTVQybEtyWm9sYkZyVExWYw==</auth>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj16c21uTVQybEtyWm9sYkZyVExWYzVlUzVGd3lhd3ZXVU5aVjZUMTRkLHM9akVOcXNqUlFsVE5TWVE9PSxpPTQwOTY=</challenge>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9enNtbk1UMmxLclpvbGJGclRMVmM1ZVM1Rnd5YXd2V1VOWlY2VDE0ZCxwPWVnUmxvSVUyNjBiTDRuNTZFU3RWNVV4bmZWbE5BL0dhUWV1eGhPQmtEQ2M9</response>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1obHlHL1A2elJ3SERqUmtkN2YrY00wNmxzTDR3cnl1L3R2emkzeTFiYXhrPQ==</success>

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost >> <iq xmlns="jabber:client" id="WYocIFDnfAzl8DHBAtneWmO" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost << <iq xmlns="jabber:client" to="jaxmpp__33ffjl0529@localhost/993927746-tigase-286" type="result" id="WYocIFDnfAzl8DHBAtneWmO"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__33ffjl0529@localhost/993927746-tigase-286</jid></bind></iq>

2019-11-26 18:50:35 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 >> <iq xmlns="jabber:client" id="I5HfAaqWdGDGLVeKSNU3pVl" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 << <iq xmlns="jabber:client" to="jaxmpp__33ffjl0529@localhost/993927746-tigase-286" type="result" id="I5HfAaqWdGDGLVeKSNU3pVl"/>

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 >> <iq to="localhost" id="NIyOS1sj1q8El0fNHTS9vsh" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 >> <iq id="C4AKS3xyAEoMEo7ufUdP2m6" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-26 18:50:35 | jaxmpp_ :: jaxmpp__33ffjl0529@localhost/993927746-tigase-286 << <enabled id="60b78af2-c4e7-4c48-9cac-b2ce404c81b3" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/>

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2019-11-26 18:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-26 18:50:35 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-11-26 18:50:35 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted