Testing state of JaXMPP instance after issues with connection or authentication timeout - #4378

Test duration: 64.931s

Passed Tests
tigase.tests.jaxmpp.TestHandlingOfConnectionIssues
testStateAfterAuthTimeoutBosh 20.604s
2020-06-02 13:48:18 |

2020-06-02 13:48:18 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:48:18 | ------------------------------------

2020-06-02 13:48:18 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:48:18 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:48:18 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-06-02 13:48:18 | jaxmpp_ :: jaxmpp__3ooxwa0555@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>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@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>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzNvb3h3YTA1NTUscj1GUjVHMUdtYWZEc3ZDR0Q5Y3ZJdg==</auth>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GUjVHMUdtYWZEc3ZDR0Q5Y3ZJdkJIekZPMUUxV2dkODZUbGFpS3hLLHM9RFNTRS9XY3VISlVDTnc9PSxpPTQwOTY=</challenge>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RlI1RzFHbWFmRHN2Q0dEOWN2SXZCSHpGTzFFMVdnZDg2VGxhaUt4SyxwPXRNalJxZ3R6cndpZmxaVFNUYmUyTWsyS3BVOHkvSVZRbXdBOGJPWFNrNXM9</response>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1LbXE3MzZTOTJ6U1JlQk1kVVdUa1B4OTJyVjBkSlEvVVNRTGlFdDJqR1VFPQ==</success>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost >> <iq xmlns="jabber:client" id="PAHbqfQZcLBCFyq8JCkZiS0" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost >> <iq xmlns="jabber:client" id="2yzyrcGCJzq3iXobyo4l9m7" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost >> <iq xmlns="jabber:client" id="8UBDyjOZsjOU6phCDCxiXZo" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-311 >> <iq to="localhost" id="KUzRU6ubPIP0jQ3bMZcIITW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-311 << <iq id="2yzyrcGCJzq3iXobyo4l9m7" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-311"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3ooxwa0555@localhost/2095755800-tigase-311</jid></bind></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-311 << <iq id="8UBDyjOZsjOU6phCDCxiXZo" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-312"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3ooxwa0555@localhost/2095755800-tigase-312</jid></bind></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-312 >> <iq id="eVATYeKHRMXgPS7j2veMQ9s" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-312 << <iq id="PAHbqfQZcLBCFyq8JCkZiS0" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313</jid></bind></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-312 >> <iq to="localhost" id="CK1CJzq3iXobyo4l9m7AtaH" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 >> <iq id="5N8S0v7wfGJWhKMQTfyvm1g" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <iq id="KUzRU6ubPIP0jQ3bMZcIITW" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <iq id="eVATYeKHRMXgPS7j2veMQ9s" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 >> <iq to="localhost" id="fToiS2h16Byp2rXk5o6ur2a" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <iq id="CK1CJzq3iXobyo4l9m7AtaH" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <iq id="5N8S0v7wfGJWhKMQTfyvm1g" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 >> <iq id="CN4IVXcBo9MX6ugJVUdMRWf" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <presence xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost" from="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza

2020-06-02 13:48:38 | jaxmpp_ :: jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313 << <iq id="fToiS2h16Byp2rXk5o6ur2a" type="result" xmlns="jabber:client" to="jaxmpp__3ooxwa0555@localhost/2095755800-tigase-313" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-02 13:48:38 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2020-06-02 13:48:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500
testStateAfterAuthTimeoutSocket 20.290s
2020-06-02 13:48:39 |

2020-06-02 13:48:39 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:48:39 | ------------------------------------

2020-06-02 13:48:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] Connector  state changed: null->connecting

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] DNS entry stored in session object: localhost:5222

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] Preparing connection to [localhost:5222]

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] Connector  state changed: connecting->connected

2020-06-02 13:48:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=1] Whitespace ping period is setted to nullms

2020-06-02 13:48:39 | jaxmpp_ :: jaxmpp__ei4m3u0556@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>

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=4058] Start TLS

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=4060] Proceeding TLS

2020-06-02 13:48:39 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-06-02 13:48:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=4060] Start handshake

2020-06-02 13:48:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:39 | jaxmpp_ :: jaxmpp__ei4m3u0556@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>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=4060] Connector  state changed: connected->disconnected

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=629 thread=4060] Stream terminated

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Connector  state changed: null->connecting

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] DNS entry stored in session object: localhost:5222

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Preparing connection to [localhost:5222]

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Connector  state changed: connecting->connected

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Whitespace ping period is setted to nullms

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@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>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=4058] Start TLS

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=4065] Proceeding TLS

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=4065] Start handshake

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@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>

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2VpNG0zdTA1NTYscj1VYzdxU1kycGd6RTI1Y1JwVDRYag==</auth>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1VYzdxU1kycGd6RTI1Y1JwVDRYam9VSjZkc0FOb1lIb3JEWVY5cDR2LHM9N3Erc2NvZkxtWHVCT1E9PSxpPTQwOTY=</challenge>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VWM3cVNZMnBnekUyNWNScFQ0WGpvVUo2ZHNBTm9ZSG9yRFlWOXA0dixwPXdoN0xSSTZhTzAycnNIU1lkc0FaZ1N2QWRzQUUwSzg2ZWN6Q0pCSzF5b289</response>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1OenhSU29MNzA1dlZqaUk0aTRWMnhOU2tseENJRkdyRFE5bWsxZkhjRlRBPQ==</success>

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <iq xmlns="jabber:client" id="aR8NMVk3iS2h16Byp2rXk5o" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <iq xmlns="jabber:client" id="XFwMZcIITWYhS2k9p6p0neR" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost >> <iq xmlns="jabber:client" id="Z2BKOUhIwn4q6HviNMVk3iS" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost << <iq id="aR8NMVk3iS2h16Byp2rXk5o" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-314"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ei4m3u0556@localhost/2095755800-tigase-314</jid></bind></iq>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-314 >> <iq xmlns="jabber:client" id="HPWo9MX6ugJVUdMRWfzyxiU" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-314 << <iq id="XFwMZcIITWYhS2k9p6p0neR" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-315"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ei4m3u0556@localhost/2095755800-tigase-315</jid></bind></iq>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-315 >> <iq xmlns="jabber:client" id="XIzSbAugZ8m3q8wxbEvcL0p" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-315 << <iq id="Z2BKOUhIwn4q6HviNMVk3iS" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316</jid></bind></iq>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq xmlns="jabber:client" id="OWdXnO1o5o9naLKOUhIwn4q" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="HPWo9MX6ugJVUdMRWfzyxiU" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"/>

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq to="localhost" id="cRWzCDscGzBgVcHCBhYnYgO" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="XIzSbAugZ8m3q8wxbEvcL0p" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"/>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq id="t7GU8yziGHIIGRPW5m5EHAs" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <enabled resume="true" id="5de349e0-708c-4ddf-9937-755cd72914b0" location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3"/>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="OWdXnO1o5o9naLKOUhIwn4q" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"/>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq to="localhost" id="kaNYl2nYjHO3kYeV06xo4oh" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="cRWzCDscGzBgVcHCBhYnYgO" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq id="L7CKMQTfyvm1gTR8HCyofEo" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq to="localhost" id="i1i7HsjYfINFKV4scRSQ1m0" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <iq id="gW1EAndun6vePJSbAugZ8m3" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="t7GU8yziGHIIGRPW5m5EHAs" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <presence xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost" from="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Connector  state changed: connected->disconnecting

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=1] Terminating XMPP Stream

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="kaNYl2nYjHO3kYeV06xo4oh" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="L7CKMQTfyvm1gTR8HCyofEo" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="i1i7HsjYfINFKV4scRSQ1m0" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <iq id="gW1EAndun6vePJSbAugZ8m3" type="result" xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316 << <presence xmlns="jabber:client" to="jaxmpp__ei4m3u0556@localhost" from="jaxmpp__ei4m3u0556@localhost/2095755800-tigase-316"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=630 thread=4065] Connector  state changed: disconnecting->disconnected
testStateAfterAuthTimeoutWebSocket 20.373s
2020-06-02 13:48:59 |

2020-06-02 13:48:59 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:48:59 | ------------------------------------

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-06-02 13:48:59 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-06-02 13:48:59 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:48:59 | jaxmpp_ :: jaxmpp__b95sl0557@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>

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

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

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

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

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

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

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

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

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

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

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@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>

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

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2I5NXNsMDU1NyxyPVhFWllOWVpKaVVVam1BWUFuakxC</auth>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1YRVpZTllaSmlVVWptQVlBbmpMQmJxajZNZlJDUEZ3ZjRMSmh0TWIwLHM9U1Z6NGZnd2pMdVJpUFE9PSxpPTQwOTY=</challenge>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WEVaWU5ZWkppVVVqbUFZQW5qTEJicWo2TWZSQ1BGd2Y0TEpodE1iMCxwPUx1dllhRHh6YnpuVGJGb3Nod05uU3FuN254YWRsdUVKQmtDTTVxVldRU2c9</response>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1WRm82S3c3U1FVTWZmeE1VL3hGTDZmSUg1bUgyWjV5OS84dnd5d3dFREhnPQ==</success>

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost >> <iq xmlns="jabber:client" id="Ej0raxgTgV2m0dTQ5wlUbri" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost >> <iq xmlns="jabber:client" id="lTI9EBqdvqhMS2xeyrcGCJz" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost >> <iq xmlns="jabber:client" id="V4d7j2veMQ9s0l0iQbteFwp" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost << <iq id="Ej0raxgTgV2m0dTQ5wlUbri" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-317"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__b95sl0557@localhost/2095755800-tigase-317</jid></bind></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost << <iq id="lTI9EBqdvqhMS2xeyrcGCJz" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-318"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__b95sl0557@localhost/2095755800-tigase-318</jid></bind></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost << <iq id="V4d7j2veMQ9s0l0iQbteFwp" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-319"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__b95sl0557@localhost/2095755800-tigase-319</jid></bind></iq>

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq xmlns="jabber:client" id="YhOnYgO5kKPYeIVXcBo9MX6" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq xmlns="jabber:client" id="klYk3sdEFpeT0kWlQMT6ueX" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq xmlns="jabber:client" id="j6ddun6vePJSbAugZ8m3q8w" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 << <iq id="YhOnYgO5kKPYeIVXcBo9MX6" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-319"/>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 << <iq id="klYk3sdEFpeT0kWlQMT6ueX" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-319"/>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 << <iq id="j6ddun6vePJSbAugZ8m3q8w" type="result" xmlns="jabber:client" to="jaxmpp__b95sl0557@localhost/2095755800-tigase-319"/>

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq to="localhost" id="F1WubytqfGEAndun6vePJSb" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq to="localhost" id="z1UtgOSfBubytqfGEAndun6" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq to="localhost" id="wevNKAvkW8LDyjOZsjOU6ph" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq id="bah4vunWaCrlKT8zm5fUfIK" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq id="JlMm7q8yj5eR4r6zdQ7seKX" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <iq id="vjEUhIwn4q6HviNMVk3iS2h" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

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

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-06-02 13:49:19 | jaxmpp_ :: jaxmpp__b95sl0557@localhost/2095755800-tigase-319 << <enabled resume="true" id="d581470b-e2ad-4f9c-ba73-b97867328530" location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3"/>

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-02 13:49:19 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:49:19 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected
testStateAfterConnectionFailureBosh 0.796s
2020-06-02 13:49:20 |

2020-06-02 13:49:20 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:49:20 | ------------------------------------

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@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>

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3VrbjM5ZTA1NTgscj1YRUxLMFFTMjB0Z2dNZ2VLSHZpaQ==</auth>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1YRUxLMFFTMjB0Z2dNZ2VLSHZpaTk5OTVWcGNkejVPdHpoSk9LYmE2LHM9MHBpVkkyeWErbVh0ZGc9PSxpPTQwOTY=</challenge>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WEVMSzBRUzIwdGdnTWdlS0h2aWk5OTk1VnBjZHo1T3R6aEpPS2JhNixwPVZsUm9qOHBVdSs1SFVxbEFvenpGTEFLMTNsTmV3a2lwUDgzRWcwUGM4ams9</response>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1rOTFwb2UxaytFOHBrZmlhdTh4aENNZmJocGEzci9YR2tEL3o0Y1BiYzNJPQ==</success>

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost >> <iq xmlns="jabber:client" id="tu4FEADqWdKMO7lY9ulaJEA" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost << <iq id="tu4FEADqWdKMO7lY9ulaJEA" type="result" xmlns="jabber:client" to="jaxmpp__ukn39e0558@localhost/2095755800-tigase-320"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ukn39e0558@localhost/2095755800-tigase-320</jid></bind></iq>

2020-06-02 13:49:20 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost/2095755800-tigase-320 >> <iq to="localhost" id="5oK5qlNSX6zCDscGzBgVcHC" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost/2095755800-tigase-320 >> <iq id="Ihnqk3iS2h16Byp2rXk5o6u" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:20 | jaxmpp_ :: jaxmpp__ukn39e0558@localhost/2095755800-tigase-320 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:49:20 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]
testStateAfterConnectionFailureSocket 0.277s
2020-06-02 13:49:21 |

2020-06-02 13:49:21 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:49:21 | ------------------------------------

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Connector  state changed: null->connecting

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] DNS entry stored in session object: missing:5222

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Preparing connection to [missing:5222]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] missing. Trying next.

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Connector  state changed: connecting->disconnected

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Connector  state changed: null->connecting

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] DNS entry stored in session object: localhost:5222

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Preparing connection to [localhost:5222]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Connector  state changed: connecting->connected

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Whitespace ping period is setted to nullms

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@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>

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=4117] Start TLS

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=4121] Proceeding TLS

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=4121] Start handshake

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@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>

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2R0Z2h0cDA1NTkscj0xWVU2dTJzYjNMc2JoOFdEVEZLUw==</auth>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0xWVU2dTJzYjNMc2JoOFdEVEZLUzZ2ZHdHaUJTb3h4aWpFaU82R3R3LHM9MFg5MnlQWHMxcE9xL2c9PSxpPTQwOTY=</challenge>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MVlVNnUyc2IzTHNiaDhXRFRGS1M2dmR3R2lCU294eGlqRWlPNkd0dyxwPUpkaDdSdUtKcHB5NmR5K3RGMCtrbS9rZTVKR09LMHhNd1FaeVphOGYzVFU9</response>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj11dXlZYjNITkdMcVB2Sk5ZazQvMWYybVdoRzNXZW4yQmdYbVNJMUlEcEgwPQ==</success>

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost >> <iq xmlns="jabber:client" id="Yz7YaEADqWdKMO7lY9ulaJE" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost << <iq id="Yz7YaEADqWdKMO7lY9ulaJE" type="result" xmlns="jabber:client" to="jaxmpp__dtghtp0559@localhost/2095755800-tigase-321"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__dtghtp0559@localhost/2095755800-tigase-321</jid></bind></iq>

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 >> <iq xmlns="jabber:client" id="TwWucLBCFyq8JCkZiS0v7wf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 << <iq id="TwWucLBCFyq8JCkZiS0v7wf" type="result" xmlns="jabber:client" to="jaxmpp__dtghtp0559@localhost/2095755800-tigase-321"/>

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 >> <iq to="localhost" id="GtL2gTgV2m0dTQ5wlUbri1g" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 << <enabled resume="true" id="68504ce1-6891-4b50-a65a-0e2203187dbf" location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3"/>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 >> <iq id="uqANSdAgR2mYrZgJEFO1eKM" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 << <iq id="GtL2gTgV2m0dTQ5wlUbri1g" type="result" xmlns="jabber:client" to="jaxmpp__dtghtp0559@localhost/2095755800-tigase-321" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10750/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 << <iq id="uqANSdAgR2mYrZgJEFO1eKM" type="result" xmlns="jabber:client" to="jaxmpp__dtghtp0559@localhost/2095755800-tigase-321"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Connector  state changed: connected->disconnecting

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=1] Terminating XMPP Stream

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__dtghtp0559@localhost/2095755800-tigase-321 << <presence xmlns="jabber:client" to="jaxmpp__dtghtp0559@localhost" from="jaxmpp__dtghtp0559@localhost/2095755800-tigase-321"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=4121] Connector  state changed: disconnecting->disconnected

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=635 thread=4121] Stream terminated

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testStateAfterConnectionFailureWebSocket 0.307s
2020-06-02 13:49:21 |

2020-06-02 13:49:21 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2020-06-02 13:49:21 | ------------------------------------

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-06-02 13:49:21 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__ttzr8i0560@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>

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3R0enI4aTA1NjAscj1uTzZORUE1YlBVdVdiYnE0eG9lcw==</auth>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1uTzZORUE1YlBVdVdiYnE0eG9lc3NNenV4RmMxdThMWnNsUXJJVlZXLHM9Y3ZpODJlbEhOMlROZ1E9PSxpPTQwOTY=</challenge>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bk82TkVBNWJQVXVXYmJxNHhvZXNzTXp1eEZjMXU4TFpzbFFySVZWVyxwPWFkTUorZS8wby9YK0l3bk9xbys1MEs0NnQ4T0N5S2xXOW1ILzFLNkI4NjQ9</response>

2020-06-02 13:49:21 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1DaXRvcERDYXpVa0VJRm5WM2hyQ1JReFptRC9wVTdSR3VqVFFUT29NK2FzPQ==</success>

2020-06-02 13:49:21 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@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 ver="nBLDMRvlNStpdRJBRyp3h195a5U=" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost >> <iq xmlns="jabber:client" id="kJn7DCxiXZodECwvaqbGEyB" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost << <iq id="kJn7DCxiXZodECwvaqbGEyB" type="result" xmlns="jabber:client" to="jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322</jid></bind></iq>

2020-06-02 13:49:22 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 >> <iq xmlns="jabber:client" id="5BXvg19AnWfYlS2mVjGLS3k" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 << <iq id="5BXvg19AnWfYlS2mVjGLS3k" type="result" xmlns="jabber:client" to="jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322"/>

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 >> <iq to="localhost" id="TE4KINFKV4scRSQ1m0g19An" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 >> <iq id="tKkbGELGsfOU4Dn2hKRcNMQ" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-06-02 13:49:22 | jaxmpp_ :: jaxmpp__ttzr8i0560@localhost/2095755800-tigase-322 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-06-02 13:49:22 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-06-02 13:49:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-06-02 13:49:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted