Test duration: 64.660s
| Passed Tests | ||
|---|---|---|
| tigase.tests.jaxmpp.TestHandlingOfConnectionIssues | ||
| testStateAfterAuthTimeoutBosh | 20.606s |
2019-11-26 18:50:36 |
2019-11-26 18:50:36 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:50:36 | ------------------------------------ 2019-11-26 18:50:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:50:36 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:50:36 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-26 18:50:36 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3JrZHpqcTA1MzAscj1HWmd2SHd1b0hDd3lCN2RaYnFrbg==</auth> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HWmd2SHd1b0hDd3lCN2RaYnFrbnUwaGV3ZVgxZ2t4MVhNMDlLYjgzLHM9clNCWjJ0alJ6RmNFd0E9PSxpPTQwOTY=</challenge> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9R1pndkh3dW9IQ3d5QjdkWmJxa251MGhld2VYMWdreDFYTTA5S2I4MyxwPUhXcHpFa0ZIQk9QVS9kSnJoV25iNXRydW03NDdUZnM3eWxFdnRXc2NFSnM9</response> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1LUVlkbm9Mazl0OFBLT2tVNi9Da0xMWDdIcitnQVJld2sxTThDMWUvbVA0PQ==</success> 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost >> <iq xmlns="jabber:client" id="TaCZgUR2jVXpUdDIN1fS3xy" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost >> <iq xmlns="jabber:client" id="1SIcBEFLJQZpi4CkEoS5q7E" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost >> <iq xmlns="jabber:client" id="704T5BGQaqQMEDwj2tl4qev" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <iq xmlns="jabber:client" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-288" type="result" id="TaCZgUR2jVXpUdDIN1fS3xy"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__rkdzjq0530@localhost/993927746-tigase-288</jid></bind></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <iq xmlns="jabber:client" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-289" type="result" id="1SIcBEFLJQZpi4CkEoS5q7E"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__rkdzjq0530@localhost/993927746-tigase-289</jid></bind></iq> 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <iq to="localhost" id="mdF6AsiDAp7lbsjP6zzl4rj" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <iq to="localhost" id="j5f8GLVeKSNU3pVlMX7GJTZ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <iq id="F9pCIUiGmOILMP4xtkWgzs9" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <iq id="SgIcMQ4bGBv7JyvdS7FxsdR" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-289 << <iq xmlns="jabber:client" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" type="result" id="704T5BGQaqQMEDwj2tl4qev"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__rkdzjq0530@localhost/993927746-tigase-290</jid></bind></iq> 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 >> <iq to="localhost" id="OdxHFKTbKMWVazrZt2t9KR9" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 >> <iq id="ucsohEHBEFLJQZpi4CkEoS5" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 << <iq xmlns="jabber:client" from="localhost" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" type="result" id="j5f8GLVeKSNU3pVlMX7GJTZ"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 << <iq xmlns="jabber:client" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" type="result" id="SgIcMQ4bGBv7JyvdS7FxsdR"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 << <iq xmlns="jabber:client" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" type="result" id="F9pCIUiGmOILMP4xtkWgzs9"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost/993927746-tigase-290 << <presence from="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" to="jaxmpp__rkdzjq0530@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@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-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <iq xmlns="jabber:client" from="localhost" to="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" type="result" id="OdxHFKTbKMWVazrZt2t9KR9"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__rkdzjq0530@localhost << <presence from="jaxmpp__rkdzjq0530@localhost/993927746-tigase-290" to="jaxmpp__rkdzjq0530@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 |
| testStateAfterAuthTimeoutSocket | 20.260s |
2019-11-26 18:50:56 |
2019-11-26 18:50:56 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:50:56 | ------------------------------------ 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] Connector state changed: null->connecting 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] DNS entry stored in session object: localhost:5222 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] Preparing connection to [localhost:5222] 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] Connector state changed: connecting->connected 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=1] Whitespace ping period is setted to nullms 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=3822] Start TLS 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=3824] Proceeding TLS 2019-11-26 18:50:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=3824] Start handshake 2019-11-26 18:50:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:50:56 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=3824] Connector state changed: connected->disconnected 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=590 thread=3824] Stream terminated 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Connector state changed: null->connecting 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] DNS entry stored in session object: localhost:5222 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Preparing connection to [localhost:5222] 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Connector state changed: connecting->connected 2019-11-26 18:51:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Whitespace ping period is setted to nullms 2019-11-26 18:51:16 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=3822] Start TLS 2019-11-26 18:51:16 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=3829] Proceeding TLS 2019-11-26 18:51:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=3829] Start handshake 2019-11-26 18:51:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3RyM2ZwbDA1MzEscj1HNDAycDBKOUxQeldHMTQ4RDMwcg==</auth> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HNDAycDBKOUxQeldHMTQ4RDMwcmgzS1pHb1A5ZXdFYk5IODRwTlNVLHM9ZU42bGZsTUNpMUZ1RkE9PSxpPTQwOTY=</challenge> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RzQwMnAwSjlMUHpXRzE0OEQzMHJoM0taR29QOWV3RWJOSDg0cE5TVSxwPU4zdmFpL0tDclkwL29JZW1HbjRnTk5EbjhBb1FsT3JkMTF2bHJXNTVhQjg9</response> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1XWXZjS1hscXRSNXZNSzdSL0c0a29ENmh1TjV2TEV6UjFIZHhOYUdpVldRPQ==</success> 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <iq xmlns="jabber:client" id="cr3X006iV6tm7DEAy9CtfBH" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <iq xmlns="jabber:client" id="OjDT3wrhWaItgLPL18Fxwpf" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost >> <iq xmlns="jabber:client" id="Pfpsk3vo4w5udJzDm7rk1t9" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-291" type="result" id="cr3X006iV6tm7DEAy9CtfBH"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__tr3fpl0531@localhost/993927746-tigase-291</jid></bind></iq> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-291 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-292" type="result" id="OjDT3wrhWaItgLPL18Fxwpf"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__tr3fpl0531@localhost/993927746-tigase-292</jid></bind></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-292 >> <iq xmlns="jabber:client" id="ujzCJyvdS7FxsdRcSey6iGH" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-292 >> <iq xmlns="jabber:client" id="tgpt3BiZbOZp1q9JWmcuY2a" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-292 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="Pfpsk3vo4w5udJzDm7rk1t9"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__tr3fpl0531@localhost/993927746-tigase-293</jid></bind></iq> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq xmlns="jabber:client" id="qqR7ylZ5FqfHQ3xhWbN4CIw" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="ujzCJyvdS7FxsdRcSey6iGH"/> 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq to="localhost" id="TsVAsiDAp7lbsjP6zzl4rj6" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="tgpt3BiZbOZp1q9JWmcuY2a"/> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq id="1a1NN2rjP2nbQaOIqWYhOTc" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <enabled id="0cd4d194-c520-468b-b59f-95d0fc887c68" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq to="localhost" id="PlwFKTbKMWVazrZt2t9KR9v" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="qqR7ylZ5FqfHQ3xhWbN4CIw"/> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@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-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq id="upGP9obzAxvdOVh2yAgw8m3" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq to="localhost" id="FqHbQaOIqWYhOTcNZm0oPYb" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 >> <iq id="tnwHvv6BpgTdDEBDKYqWSQM" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="1a1NN2rjP2nbQaOIqWYhOTc"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@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-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" from="localhost" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="TsVAsiDAp7lbsjP6zzl4rj6"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@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-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" from="localhost" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="PlwFKTbKMWVazrZt2t9KR9v"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Connector state changed: connected->disconnecting 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=1] Terminating XMPP Stream 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <presence from="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" to="jaxmpp__tr3fpl0531@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="upGP9obzAxvdOVh2yAgw8m3"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <iq xmlns="jabber:client" to="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" type="result" id="tnwHvv6BpgTdDEBDKYqWSQM"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=3829] Connector state changed: disconnecting->disconnected 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=591 thread=3829] Stream terminated 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <presence from="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" to="jaxmpp__tr3fpl0531@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__tr3fpl0531@localhost/993927746-tigase-293 << <presence from="jaxmpp__tr3fpl0531@localhost/993927746-tigase-293" to="jaxmpp__tr3fpl0531@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |
| testStateAfterAuthTimeoutWebSocket | 20.371s |
2019-11-26 18:51:17 |
2019-11-26 18:51:17 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:51:17 | ------------------------------------ 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-26 18:51:17 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-26 18:51:17 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:17 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3BobXUxbDA1MzIscj1mUGh6b3JaY3ZZTDZreGxRamVLTw==</auth> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1mUGh6b3JaY3ZZTDZreGxRamVLT1Ntc3hZa2pVaWFweUZFZVVJVGRNLHM9TUVhYlpnUm13REx6WFE9PSxpPTQwOTY=</challenge> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZlBoem9yWmN2WUw2a3hsUWplS09TbXN4WWtqVWlhcHlGRWVVSVRkTSxwPXFlbzZvMTR0V0Y5Z3F1Vmk5Z2d1RVJ5cnZoLzhCRjNNbExTeWVKQ2hkSzg9</response> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1UdUZWenNaUDdNbmxkdC8zbGYyYnhaSTgzNURGUjA4clkxRlJzeWpQUElJPQ==</success> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost >> <iq xmlns="jabber:client" id="hi9ZeJS9HMQ4bGBv7JyvdS7" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost >> <iq xmlns="jabber:client" id="bHkus006iV6tm7DEAy9CtfB" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost >> <iq xmlns="jabber:client" id="yD6LYj3j0j3AEMEiGsfKLWh" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-294" type="result" id="hi9ZeJS9HMQ4bGBv7JyvdS7"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__phmu1l0532@localhost/993927746-tigase-294</jid></bind></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-295" type="result" id="bHkus006iV6tm7DEAy9CtfB"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__phmu1l0532@localhost/993927746-tigase-295</jid></bind></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-296" type="result" id="yD6LYj3j0j3AEMEiGsfKLWh"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__phmu1l0532@localhost/993927746-tigase-296</jid></bind></iq> 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="LfUgCzo3wrhWaItgLPL18Fx" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="nF80iYZiPX1xaJFMVhLU7FO" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="xnedDIN1fS3xyAEoMEo7ufU" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-296" type="result" id="LfUgCzo3wrhWaItgLPL18Fx"/> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-296" type="result" id="nF80iYZiPX1xaJFMVhLU7FO"/> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 << <iq xmlns="jabber:client" to="jaxmpp__phmu1l0532@localhost/993927746-tigase-296" type="result" id="xnedDIN1fS3xyAEoMEo7ufU"/> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq to="localhost" id="iDYynaJR6umLQV9pjIPRaHP" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq to="localhost" id="mIb7CwqT6rbtrYlTeP5n8zz" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq to="localhost" id="IOrIw2aqbCvqfTaCChSZhFP" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq id="QCPqfTaCChSZhFPKTZqdRVe" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq id="6NkdOVh2yAgw8m3mZobLU6A" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <iq id="0Sxwr5CBDtkXlaO6iAgCzo3" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-26 18:51:37 | jaxmpp_ :: jaxmpp__phmu1l0532@localhost/993927746-tigase-296 << <enabled id="70dbb596-22a7-44af-bc57-cc8a4b4850ac" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/> 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected |
| testStateAfterConnectionFailureBosh | 0.755s |
2019-11-26 18:51:37 |
2019-11-26 18:51:37 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:51:37 | ------------------------------------ 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-26 18:51:37 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-26 18:51:37 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream] 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzEzMnUxcTA1MzMscj1PdDNBUXpIQlZCajVTcTFPYmFLYQ==</auth> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1PdDNBUXpIQlZCajVTcTFPYmFLYVJIV01mWWFlRng4elFlM3Z6elRBLHM9amNKMDdQM0JJdVd6Wnc9PSxpPTQwOTY=</challenge> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9T3QzQVF6SEJWQmo1U3ExT2JhS2FSSFdNZllhZUZ4OHpRZTN2enpUQSxwPTQ5UVRnemkyVWNMYnVLRmNyc0RDeWNoK0NTSGplM3B5bGdJVTI3eG11cWc9</response> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1nRkFITHU4YjNKUVB6ekFZWXc4Vy9mL3FyUHFyUHJ1MkRIbllwMXJZZjUwPQ==</success> 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost >> <iq xmlns="jabber:client" id="fr6Eo7ufUdP2m6fOR19NGLJ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost << <iq xmlns="jabber:client" to="jaxmpp__132u1q0533@localhost/993927746-tigase-297" type="result" id="fr6Eo7ufUdP2m6fOR19NGLJ"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__132u1q0533@localhost/993927746-tigase-297</jid></bind></iq> 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost/993927746-tigase-297 >> <iq to="localhost" id="zFKiXlTaDHSeWYWY2p4peR7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@localhost/993927746-tigase-297 >> <iq id="CUrrgP5EM2akKAvgN0pdOUc" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__132u1q0533@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-26 18:51:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureSocket | 0.245s |
2019-11-26 18:51:38 |
2019-11-26 18:51:38 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:51:38 | ------------------------------------ 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=595 thread=1] Connector state changed: null->connecting 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=595 thread=1] DNS entry stored in session object: missing:5222 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=595 thread=1] Preparing connection to [missing:5222] 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=595 thread=1] missing. Trying next. 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=595 thread=1] Connector state changed: connecting->disconnected 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream] 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Connector state changed: null->connecting 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] DNS entry stored in session object: localhost:5222 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Preparing connection to [localhost:5222] 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Connector state changed: connecting->connected 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Whitespace ping period is setted to nullms 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=3882] Start TLS 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=3886] Proceeding TLS 2019-11-26 18:51:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=3886] Start handshake 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3YzYWtibDA1MzQscj1Ob3pUU0hZOUtxdjd5aVpZZlRoeg==</auth> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1Ob3pUU0hZOUtxdjd5aVpZZlRoekdhYklIWkQxbmRnejBjeTNUQmp0LHM9bytEZldaaklCQ3hpQ0E9PSxpPTQwOTY=</challenge> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Tm96VFNIWTlLcXY3eWlaWWZUaHpHYWJJSFpEMW5kZ3owY3kzVEJqdCxwPUp1L0RjSmJpNnZZZWIyZ1VkVkVGQTU3OThNRUpnZnJHcHZsWFgxVlNJUmM9</response> 2019-11-26 18:51:38 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1VL0FTdlJKMVdickUzbFIyeEkvWVpjd20vM0hYdzZhZWdjWWw2ZEIrbTZ3PQ==</success> 2019-11-26 18:51:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost >> <iq xmlns="jabber:client" id="ZMVv7JyvdS7FxsdRcSey6iG" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost << <iq xmlns="jabber:client" to="jaxmpp__v3akbl0534@localhost/993927746-tigase-298" type="result" id="ZMVv7JyvdS7FxsdRcSey6iG"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__v3akbl0534@localhost/993927746-tigase-298</jid></bind></iq> 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 >> <iq xmlns="jabber:client" id="1EvLJUbNNYfVk8KAaqWdGDG" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 << <iq xmlns="jabber:client" to="jaxmpp__v3akbl0534@localhost/993927746-tigase-298" type="result" id="1EvLJUbNNYfVk8KAaqWdGDG"/> 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 >> <iq to="localhost" id="9S95EM2akKAvgN0pdOUcrcD" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 << <enabled id="2202585b-2d41-4f0e-9ff7-c621e36b2d6f" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 >> <iq id="5XmvdOVh2yAgw8m3mZobLU6" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@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-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 << <iq xmlns="jabber:client" to="jaxmpp__v3akbl0534@localhost/993927746-tigase-298" type="result" id="5XmvdOVh2yAgw8m3mZobLU6"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Connector state changed: connected->disconnecting 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=1] Terminating XMPP Stream 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 << <iq xmlns="jabber:client" from="localhost" to="jaxmpp__v3akbl0534@localhost/993927746-tigase-298" type="result" id="9S95EM2akKAvgN0pdOUcrcD"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10591/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__v3akbl0534@localhost/993927746-tigase-298 << <presence from="jaxmpp__v3akbl0534@localhost/993927746-tigase-298" to="jaxmpp__v3akbl0534@localhost" xmlns="jabber:client"><c node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" xmlns="http://jabber.org/protocol/caps"/></presence> 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=596 thread=3886] Connector state changed: disconnecting->disconnected 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureWebSocket | 0.373s |
2019-11-26 18:51:39 |
2019-11-26 18:51:39 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-26 18:51:39 | ------------------------------------ 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session, stream] 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzZmNzllNjA1MzUscj14aHpIQUhYaVlSSm5Mc0Njd0dlbA==</auth> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj14aHpIQUhYaVlSSm5Mc0Njd0dlbEQwRVUweFJiVGVkaUJUcmp0TWZrLHM9dUJwa0d0bDlMZkpuR3c9PSxpPTQwOTY=</challenge> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eGh6SEFIWGlZUkpuTHNDY3dHZWxEMEVVMHhSYlRlZGlCVHJqdE1mayxwPWViS3Yzd3pucSt4NGJlYkFpdkhmK0RLYlVOWm50c3FQd1BrMHpZKzQ3emM9</response> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1neUt3Zlc4ekdydVZ5dExINTMzMEhaelhpMDZRSUQ0ZUdEYlBrUC9QQmQ4PQ==</success> 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" ver="BTEL0uZZSv4xXXpA3cLLdyyHkxk=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps"/></features> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost >> <iq xmlns="jabber:client" id="b8LmcuY2aFAriZn8yu1mXdx" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost << <iq xmlns="jabber:client" to="jaxmpp__6f79e60535@localhost/993927746-tigase-299" type="result" id="b8LmcuY2aFAriZn8yu1mXdx"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__6f79e60535@localhost/993927746-tigase-299</jid></bind></iq> 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 >> <iq xmlns="jabber:client" id="PV6P4xtkWgzs9Bj7k3j4vr5" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 << <iq xmlns="jabber:client" to="jaxmpp__6f79e60535@localhost/993927746-tigase-299" type="result" id="PV6P4xtkWgzs9Bj7k3j4vr5"/> 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 >> <iq to="localhost" id="xMD1fWfV3uhZsogCeycJIHO" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 >> <iq id="T6zS2sXeHHvv6BpgTdDEBDK" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@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-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-26 18:51:39 | jaxmpp_ :: jaxmpp__6f79e60535@localhost/993927746-tigase-299 << <enabled id="f10b09ab-81f3-4513-b911-c678750bac2f" max="60" location="d4270859fb27" resume="true" xmlns="urn:xmpp:sm:3"/> 2019-11-26 18:51:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-26 18:51:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |