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

Test duration: 65.018s

Passed Tests
tigase.tests.jaxmpp.TestHandlingOfConnectionIssues
testStateAfterAuthTimeoutBosh 20.709s
2019-11-21 06:02:35 |

2019-11-21 06:02:35 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:02:35 | ------------------------------------

2019-11-21 06:02:35 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:02:35 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:02:35 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-21 06:02:35 | jaxmpp_ :: jaxmpp__lxxefo0533@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-21 06:02:55 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-21 06:02:55 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:02:55 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:02:55 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-21 06:02:55 | jaxmpp_ :: jaxmpp__lxxefo0533@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-21 06:02:55 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:02:55 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:02:55 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2x4eGVmbzA1MzMscj1sczNDeHdVdk1QWGN3YnJQeWxyOA==</auth>

2019-11-21 06:02:55 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1sczNDeHdVdk1QWGN3YnJQeWxyOE9QSTBLSkxQMTJiT3dKcTczMFQ3LHM9RVcrb2p1SE5TKzZXVFE9PSxpPTQwOTY=</challenge>

2019-11-21 06:02:55 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bHMzQ3h3VXZNUFhjd2JyUHlscjhPUEkwS0pMUDEyYk93SnE3MzBUNyxwPW8zNXR1ZDBieUxYSkRjazZ4UUlPT1E1OXF4VFc3VXdERU5pbHZHeUlYM2M9</response>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1TaWdiWFFJYVdPa3JaUmFneVNqUC80cE5HaHQ5L3BoT3R0Y3BrWGI2Q2dZPQ==</success>

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost >> <iq xmlns="jabber:client" id="c3XHOCv6cqfNTfS36xcDIPI" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost >> <iq xmlns="jabber:client" id="rhBHDIPIsgV4hHIAaHv8GMy" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost >> <iq xmlns="jabber:client" id="XiCJHQ5euk3kNJMIqdxcO24" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-288" id="c3XHOCv6cqfNTfS36xcDIPI" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__lxxefo0533@localhost/993927746-tigase-288</jid></bind></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-289" id="rhBHDIPIsgV4hHIAaHv8GMy" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__lxxefo0533@localhost/993927746-tigase-289</jid></bind></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="XiCJHQ5euk3kNJMIqdxcO24" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__lxxefo0533@localhost/993927746-tigase-290</jid></bind></iq>

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq to="localhost" id="H5XpeSKLCeun3phWbm3eHQ5" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq to="localhost" id="Adj8p8qQ3dywsU5gw4xn3zw" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq to="localhost" id="us0MCgS9wbvkOCBjKUesbsU" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq id="RkBRbKNAv8vsjGo8FyfDAkE" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq id="w6TaGLKDp8qQ3dywsU5gw4x" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <iq id="dtYIq7kEk3p7HMRILExwrWU" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="RkBRbKNAv8vsjGo8FyfDAkE" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="H5XpeSKLCeun3phWbm3eHQ5" xmlns="jabber:client" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><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: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost/993927746-tigase-290 << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="dtYIq7kEk3p7HMRILExwrWU" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

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

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="w6TaGLKDp8qQ3dywsU5gw4x" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="Adj8p8qQ3dywsU5gw4xn3zw" xmlns="jabber:client" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><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: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__lxxefo0533@localhost << <iq to="jaxmpp__lxxefo0533@localhost/993927746-tigase-290" id="us0MCgS9wbvkOCBjKUesbsU" xmlns="jabber:client" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><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: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

testStateAfterAuthTimeoutSocket 20.279s
2019-11-21 06:02:56 |

2019-11-21 06:02:56 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:02:56 | ------------------------------------

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector  state changed: null->connecting

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] DNS entry stored in session object: localhost:5222

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Preparing connection to [localhost:5222]

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector  state changed: connecting->connected

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Whitespace ping period is setted to nullms

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__pbmqhh0534@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-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3846] Start TLS

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3848] Proceeding TLS

2019-11-21 06:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3848] Start handshake

2019-11-21 06:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:02:56 | jaxmpp_ :: jaxmpp__pbmqhh0534@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-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3848] Connector  state changed: connected->disconnected

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3848] Stream terminated

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector  state changed: null->connecting

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] DNS entry stored in session object: localhost:5222

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Preparing connection to [localhost:5222]

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector  state changed: connecting->connected

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Whitespace ping period is setted to nullms

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@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-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3846] Start TLS

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3853] Proceeding TLS

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3853] Start handshake

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@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-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3BibXFoaDA1MzQscj16ZmJDQUR6aHQyVWZvTnRDaWI3Zw==</auth>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj16ZmJDQUR6aHQyVWZvTnRDaWI3Z21WT21jZWlOMnR4UEZLOTFtVHF2LHM9dVh0OUoxZU8rTzkza1E9PSxpPTQwOTY=</challenge>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9emZiQ0FEemh0MlVmb050Q2liN2dtVk9tY2VpTjJ0eFBGSzkxbVRxdixwPTlYWlJ3ejNobjdPSnpHdzJMTDlTT3o0dVBjR29nOGd0QTRQZ0FqcDNOclE9</response>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1GenY1emc2T1NOb2NPVHdPLzNnUnFQMXBVVnBiUE8xK1RRSnd4SlEwdjlFPQ==</success>

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <iq xmlns="jabber:client" id="tmvO16eO1gFtgGm5hGMYW9o" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <iq xmlns="jabber:client" id="kx0FDJQZYhGBEHsWeRW1bGw" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-291" id="tmvO16eO1gFtgGm5hGMYW9o" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pbmqhh0534@localhost/993927746-tigase-291</jid></bind></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost >> <iq xmlns="jabber:client" id="jbUzrbP2nKPMN2t02qfIzhS" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-291 >> <iq xmlns="jabber:client" id="Bjg1mSKRPMYmIHuYYlZh1q9" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-291 << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-292" id="kx0FDJQZYhGBEHsWeRW1bGw" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pbmqhh0534@localhost/993927746-tigase-292</jid></bind></iq>

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-292 >> <iq xmlns="jabber:client" id="ayXnSaKufAam7xndOVQ1azA" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-292 << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-293" id="jbUzrbP2nKPMN2t02qfIzhS" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pbmqhh0534@localhost/993927746-tigase-293</jid></bind></iq>

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq xmlns="jabber:client" id="Bli5u8gTTUeSKLCeun3phWb" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-293" id="Bjg1mSKRPMYmIHuYYlZh1q9" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq to="localhost" id="ZsB0pSMUdEr1mSKRPMYmIHu" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 << <enabled location="d4270859fb27" resume="true" id="2c31c101-3b21-4174-ba0a-c9d0397ade45" xmlns="urn:xmpp:sm:3" max="60"/>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq id="vD6CgS9wbvkOCBjKUesbsUQ" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-293" id="ayXnSaKufAam7xndOVQ1azA" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq to="localhost" id="7zSgwpVYm8gNGmKT5tpcHo7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq id="fgVt24aJLP7Cx4t4ncNEk0p" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 << <iq to="jaxmpp__pbmqhh0534@localhost/993927746-tigase-293" id="Bli5u8gTTUeSKLCeun3phWb" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq to="localhost" id="nKlq9iVYbO16eO1gFtgGm5h" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <iq id="TLmsdqbudS9mKQ5oVZiKulN" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:16 | jaxmpp_ :: jaxmpp__pbmqhh0534@localhost/993927746-tigase-293 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector  state changed: connected->disconnecting

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Terminating XMPP Stream

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-21 06:03:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3853] Connector  state changed: disconnecting->disconnected

testStateAfterAuthTimeoutWebSocket 20.377s
2019-11-21 06:03:17 |

2019-11-21 06:03:17 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:03:17 | ------------------------------------

2019-11-21 06:03:17 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-21 06:03:17 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-21 06:03:17 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-21 06:03:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-21 06:03:17 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:17 | jaxmpp_ :: jaxmpp__av90iu0535@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-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@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-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2F2OTBpdTA1MzUscj1xcDVDZnB6bDFsQWpmY0d3QlZ4VA==</auth>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1xcDVDZnB6bDFsQWpmY0d3QlZ4VFdmdXYzbnN1RTdEYjh4bzFZbm1FLHM9MXZXbkp3ZTZIeGxleFE9PSxpPTQwOTY=</challenge>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cXA1Q2ZwemwxbEFqZmNHd0JWeFRXZnV2M25zdUU3RGI4eG8xWW5tRSxwPWJMcGhkWktZOTdrRlB1TlZXV21VYzEwUmdUbG91UjFpZ3RyUlRlSkxlMW89</response>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1ndUJXM0svNXVpNjhtOHF0bVlwN1BnNmthOWIrT0JNVlZlNC9KMVlKbzRzPQ==</success>

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq xmlns="jabber:client" id="7GZuYjJAAAaBiAcNT5DEBcJ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq xmlns="jabber:client" id="ULc36xcDIPIsgV4hHIAaHv8" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq xmlns="jabber:client" id="MFScoO1lZrgPEDo1unOEEHC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-294" id="7GZuYjJAAAaBiAcNT5DEBcJ" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__av90iu0535@localhost/993927746-tigase-294</jid></bind></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-295" id="ULc36xcDIPIsgV4hHIAaHv8" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__av90iu0535@localhost/993927746-tigase-295</jid></bind></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-296" id="MFScoO1lZrgPEDo1unOEEHC" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__av90iu0535@localhost/993927746-tigase-296</jid></bind></iq>

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="ByvvkOCBjKUesbsUQ5tfXiT" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="uSrDkGIPSTVauYjJAAAaBiA" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="7L4EiX1aKUOTSKwrbFNYfuj" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-296" id="ByvvkOCBjKUesbsUQ5tfXiT" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-296" id="uSrDkGIPSTVauYjJAAAaBiA" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 << <iq to="jaxmpp__av90iu0535@localhost/993927746-tigase-296" id="7L4EiX1aKUOTSKwrbFNYfuj" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <iq to="localhost" id="YAvBEHsWeRW1bGw2rZ6CEiR" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <iq to="localhost" id="hzqcqfNTfS36xcDIPIsgV4h" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq to="localhost" id="qu9Z6rk3am4xiJBwco9pWi0" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$2.run:

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq id="kUnp7HMRILExwrWUaJGv9Co" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost >> <iq id="6FCUYWfBk4y6t24aJLP7Cx4" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:37 | jaxmpp_ :: jaxmpp__av90iu0535@localhost << <enabled location="d4270859fb27" resume="true" id="c015fba5-4472-4ed1-ace8-9ac5efe8ff15" xmlns="urn:xmpp:sm:3" max="60"/>

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

testStateAfterConnectionFailureBosh 0.772s
2019-11-21 06:03:37 |

2019-11-21 06:03:37 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:03:37 | ------------------------------------

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-21 06:03:37 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2019-11-21 06:03:37 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream]

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@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-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX29uNjljMzA1MzYscj1HaGs0TkEySGw5bDc1OThPaWtGOA==</auth>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HaGs0TkEySGw5bDc1OThPaWtGOHFqSzJmRXVIZlRQOEhydjByMXdzLHM9WDV4Nk5BRjc0VHdQZEE9PSxpPTQwOTY=</challenge>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9R2hrNE5BMkhsOWw3NTk4T2lrRjhxaksyZkV1SGZUUDhIcnYwcjF3cyxwPTRNQ21UdkxBZDAvN3kvKzhYMWtLWHREOXo0Y0wrSEtpb2RKSmNCOU9MaXc9</response>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1paXNyV21GeGFPSlNYUC91aGJQWEZadXFKeFlLdnpnQUduSUZEbTNCSTZjPQ==</success>

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost >> <iq xmlns="jabber:client" id="iBgUdJLZm12pW7GLAcKT09r" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost << <iq to="jaxmpp__on69c30536@localhost/993927746-tigase-297" id="iBgUdJLZm12pW7GLAcKT09r" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__on69c30536@localhost/993927746-tigase-297</jid></bind></iq>

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost/993927746-tigase-297 >> <iq to="localhost" id="YKFViZ26yveBl0ak19qgWS1" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost/993927746-tigase-297 >> <iq id="5MJXeCePLEIQGDmIsjV9LU7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost/993927746-tigase-297 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost/993927746-tigase-297 << <iq to="jaxmpp__on69c30536@localhost/993927746-tigase-297" id="5MJXeCePLEIQGDmIsjV9LU7" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__on69c30536@localhost/993927746-tigase-297 << <iq to="jaxmpp__on69c30536@localhost/993927746-tigase-297" id="YKFViZ26yveBl0ak19qgWS1" xmlns="jabber:client" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><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: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

testStateAfterConnectionFailureSocket 0.184s
2019-11-21 06:03:38 |

2019-11-21 06:03:38 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:03:38 | ------------------------------------

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector  state changed: null->connecting

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] DNS entry stored in session object: missing:5222

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Preparing connection to [missing:5222]

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] missing. Trying next.

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector  state changed: connecting->disconnected

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream]

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector  state changed: null->connecting

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] DNS entry stored in session object: localhost:5222

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Preparing connection to [localhost:5222]

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Opening connection to localhost/127.0.0.1:5222

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector  state changed: connecting->connected

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Whitespace ping period is setted to nullms

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@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-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3906] Start TLS

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3910] Proceeding TLS

2019-11-21 06:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3910] Start handshake

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@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-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzgxeGNsbjA1Mzcscj1IRzQ0TDZ6dXdoU1F3NUg1Z1FqQw==</auth>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IRzQ0TDZ6dXdoU1F3NUg1Z1FqQ1gwR2MzMFNFZndUakpneThaaHBKLHM9ZGptSmlPOWJQUWZMa2c9PSxpPTQwOTY=</challenge>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SEc0NEw2enV3aFNRdzVINWdRakNYMEdjMzBTRWZ3VGpKZ3k4WmhwSixwPXJKTGR0MTFqQmFLTG4zdHgvYy9yZURSL05hZmh0dndSYlRpRy9CYTBlbG89</response>

2019-11-21 06:03:38 | jaxmpp_ :: jaxmpp__81xcln0537@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1VNjI2LzhsWG9OaUxueFpVMGh3bWxGVE00eW50ZTRmcDltK2N2ZjFUcUhVPQ==</success>

2019-11-21 06:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost >> <iq xmlns="jabber:client" id="YPK5CDkGIPSTVauYjJAAAaB" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost << <iq to="jaxmpp__81xcln0537@localhost/993927746-tigase-298" id="YPK5CDkGIPSTVauYjJAAAaB" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__81xcln0537@localhost/993927746-tigase-298</jid></bind></iq>

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 >> <iq xmlns="jabber:client" id="ESR4v7m8GmZ48iZhRbKNAv8" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 << <iq to="jaxmpp__81xcln0537@localhost/993927746-tigase-298" id="ESR4v7m8GmZ48iZhRbKNAv8" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 >> <iq to="localhost" id="U7yLwnSZqfy8CDu7n4iAxbu" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 << <enabled location="d4270859fb27" resume="true" id="28d4696b-6cd7-40a4-983e-d9088c5a612b" xmlns="urn:xmpp:sm:3" max="60"/>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 >> <iq id="CTQmSKRPMYmIHuYYlZh1q9i" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 << <iq to="jaxmpp__81xcln0537@localhost/993927746-tigase-298" id="U7yLwnSZqfy8CDu7n4iAxbu" xmlns="jabber:client" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10587/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><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: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="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost/993927746-tigase-298 << <iq to="jaxmpp__81xcln0537@localhost/993927746-tigase-298" id="CTQmSKRPMYmIHuYYlZh1q9i" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector  state changed: connected->disconnecting

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Terminating XMPP Stream

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3910] Connector  state changed: disconnecting->disconnected

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3910] Stream terminated

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__81xcln0537@localhost << <presence to="jaxmpp__81xcln0537@localhost" xmlns="jabber:client" from="jaxmpp__81xcln0537@localhost/993927746-tigase-298"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence>

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

testStateAfterConnectionFailureWebSocket 0.346s
2019-11-21 06:03:39 |

2019-11-21 06:03:39 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2019-11-21 06:03:39 | ------------------------------------

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream]

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@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-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzEydHRkNzA1Mzgscj1XREF5a2xaRUN0aFNBNm9VSlpLag==</auth>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1XREF5a2xaRUN0aFNBNm9VSlpLajdPcTdKcHBCb0JYYm50UmFjUVpoLHM9K0NSdlZoME83bkdVbVE9PSxpPTQwOTY=</challenge>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9V0RBeWtsWkVDdGhTQTZvVUpaS2o3T3E3SnBwQm9CWGJudFJhY1FaaCxwPVFKRkJ4aWFRdEpWQjhJclRKL0V4YjF5RWxmc1cxN1dBWVlxMzh2bys0Wm89</response>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj00N1RTcUtNWU5ETVlIWUd1NWxaK1UzNEs1L1BNekdIYXMzaVk4ekMwOU5vPQ==</success>

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@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="2tp/q0iZDbq1z+CS1yFGZIkNy2A=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost >> <iq xmlns="jabber:client" id="wSFIMX8pXeCePLEIQGDmIsj" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost << <iq to="jaxmpp__12ttd70538@localhost/993927746-tigase-299" id="wSFIMX8pXeCePLEIQGDmIsj" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__12ttd70538@localhost/993927746-tigase-299</jid></bind></iq>

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 >> <iq xmlns="jabber:client" id="8ZYiX1aKUOTSKwrbFNYfujK" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 << <iq to="jaxmpp__12ttd70538@localhost/993927746-tigase-299" id="8ZYiX1aKUOTSKwrbFNYfujK" xmlns="jabber:client" type="result"/>

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 >> <iq to="localhost" id="iQvobFDJQZYhGBEHsWeRW1b" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 >> <iq id="hXOYYlZh1q9iVYbO16eO1gF" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2019-11-21 06:03:39 | jaxmpp_ :: jaxmpp__12ttd70538@localhost/993927746-tigase-299 << <enabled location="d4270859fb27" resume="true" id="44a93832-2f50-4126-8487-d4a8c270be21" xmlns="urn:xmpp:sm:3" max="60"/>

2019-11-21 06:03:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2019-11-21 06:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]