Test duration: 65.238s
| Passed Tests | ||
|---|---|---|
| tigase.tests.jaxmpp.TestHandlingOfConnectionIssues | ||
| testStateAfterAuthTimeoutBosh | 20.661s |
2019-11-24 19:17:22 |
2019-11-24 19:17:22 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:17:22 | ------------------------------------ 2019-11-24 19:17:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:17:22 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:17:22 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-24 19:17:22 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:17:42 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-24 19:17:42 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:17:42 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:17:42 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-24 19:17:42 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:17:42 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:17:42 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:17:42 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2MxaTI3bzA1MzMscj0yYkhOYzN1MTVLQk1GaEE4ZFVtTw==</auth> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0yYkhOYzN1MTVLQk1GaEE4ZFVtT1cweWNXNWtaeG1xQ0UzaWttS3hGLHM9ZEExNWRPdnJvWGd6Q0E9PSxpPTQwOTY=</challenge> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MmJITmMzdTE1S0JNRmhBOGRVbU9XMHljVzVrWnhtcUNFM2lrbUt4RixwPWFVZEM4S09YNTZLYWhXYVcvWmRyZmMxK1pZZmxZMWdLeWhXSGh2Sjl4M2M9</response> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj12b1g3bTJJL01kU1hWL2NwMytPOVpPcjB5UmlhZHRFZmMxWk0xSnRFUFdRPQ==</success> 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost >> <iq xmlns="jabber:client" id="07rhTU17ysaBeO3jQSScxm1" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost >> <iq xmlns="jabber:client" id="T7pdtZ8vsV7zaFs7jWeIHqc" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost >> <iq xmlns="jabber:client" id="zWQgBwo2lIMZbAmQ8p6vubG" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <iq id="07rhTU17ysaBeO3jQSScxm1" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-286" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__c1i27o0533@localhost/993927746-tigase-286</jid></bind></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <iq id="zWQgBwo2lIMZbAmQ8p6vubG" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-287" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__c1i27o0533@localhost/993927746-tigase-287</jid></bind></iq> 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-287 >> <iq to="localhost" id="rZXwuZfPGHwfzsgMPGIRV4k" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-287 >> <iq to="localhost" id="P8mogJMAdt6rkFBx6xcBhS6" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-287 >> <iq id="K5bVYoV8zCrcqTPORalXfwn" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-287 << <iq id="T7pdtZ8vsV7zaFs7jWeIHqc" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__c1i27o0533@localhost/993927746-tigase-288</jid></bind></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <iq id="0dxthGzdKQW9k0r5oY4AjU7" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <iq to="localhost" id="Pbpus7y8jFudSLNVS5iNFEE" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <iq id="f68LY5sdU1mKx7o1uZfPGHw" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 << <iq from="localhost" id="rZXwuZfPGHwfzsgMPGIRV4k" xmlns="jabber:client" type="result" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 << <iq from="localhost" id="P8mogJMAdt6rkFBx6xcBhS6" xmlns="jabber:client" type="result" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost/993927746-tigase-288 << <iq id="K5bVYoV8zCrcqTPORalXfwn" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <iq id="0dxthGzdKQW9k0r5oY4AjU7" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <iq from="localhost" id="Pbpus7y8jFudSLNVS5iNFEE" xmlns="jabber:client" type="result" to="jaxmpp__c1i27o0533@localhost/993927746-tigase-288"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__c1i27o0533@localhost << <presence from="jaxmpp__c1i27o0533@localhost/993927746-tigase-288" xmlns="jabber:client" to="jaxmpp__c1i27o0533@localhost"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 |
| testStateAfterAuthTimeoutSocket | 20.290s |
2019-11-24 19:17:43 |
2019-11-24 19:17:43 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:17:43 | ------------------------------------ 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: null->connecting 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] DNS entry stored in session object: localhost:5222 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Preparing connection to [localhost:5222] 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: connecting->connected 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Whitespace ping period is setted to nullms 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><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-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3835] Start TLS 2019-11-24 19:17:43 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3837] Proceeding TLS 2019-11-24 19:17:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3837] Start handshake 2019-11-24 19:17:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:17:44 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3837] Connector state changed: connected->disconnected 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3837] Stream terminated 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: null->connecting 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] DNS entry stored in session object: localhost:5222 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Preparing connection to [localhost:5222] 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-24 19:18:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: connecting->connected 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Whitespace ping period is setted to nullms 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3835] Start TLS 2019-11-24 19:18:03 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><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-24 19:18:03 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3842] Proceeding TLS 2019-11-24 19:18:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3842] Start handshake 2019-11-24 19:18:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3E0djQ3eTA1MzQscj1UZ3dCN25EbVZYVE9zUDlGM01NRw==</auth> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1UZ3dCN25EbVZYVE9zUDlGM01NR2tkTzJReTdpQllPcHZNd1hkTk1zLHM9WFRvWTloRkxyRXNkK1E9PSxpPTQwOTY=</challenge> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VGd3QjduRG1WWFRPc1A5RjNNTUdrZE8yUXk3aUJZT3B2TXdYZE5NcyxwPVhHQUV2eXZ0ejJOOUxLaXBLano0Y09CK1BDOERxVy9LY0xjNC9odUM4ZXc9</response> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj12UmZEcXFWQXJPTFdkOXFvRFlHUkRJaFZZN1NRTVgwVVNBWEtpWGtTR2kwPQ==</success> 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <iq xmlns="jabber:client" id="facTeBuh2hSMBfExw3n5cJA" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <iq xmlns="jabber:client" id="semx5rdsfCrXeOS14up1gIy" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost >> <iq xmlns="jabber:client" id="wlFJNIDl0mYdsgX7s6k6lYf" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost << <iq id="facTeBuh2hSMBfExw3n5cJA" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-289" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__q4v47y0534@localhost/993927746-tigase-289</jid></bind></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-289 >> <iq xmlns="jabber:client" id="bnKhLKGuqZgP8tgy9lQViBt" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-289 << <iq id="semx5rdsfCrXeOS14up1gIy" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-290" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__q4v47y0534@localhost/993927746-tigase-290</jid></bind></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-290 >> <iq xmlns="jabber:client" id="x4NhGzdKQW9k0r5oY4AjU7I" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-290 << <iq id="wlFJNIDl0mYdsgX7s6k6lYf" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__q4v47y0534@localhost/993927746-tigase-291</jid></bind></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq xmlns="jabber:client" id="Agl7ysaBeO3jQSScxm1wfOE" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="bnKhLKGuqZgP8tgy9lQViBt" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"/> 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq to="localhost" id="a6Pm9veEqiJKN4l027hIFMz" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="x4NhGzdKQW9k0r5oY4AjU7I" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"/> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq id="TpIOFnU3y7vlILEv5ukU5cK" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="Agl7ysaBeO3jQSScxm1wfOE" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"/> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq to="localhost" id="TqJQJvazAbDBeNIDl0mYdsg" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <enabled max="60" location="d4270859fb27" resume="true" id="61909553-8bf8-4131-9883-d936d1f2f328" xmlns="urn:xmpp:sm:3"/> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq from="localhost" id="a6Pm9veEqiJKN4l027hIFMz" xmlns="jabber:client" type="result" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq id="5c1IMOTOYnX8s2wbEBGzsWV" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq to="localhost" id="8ozLyslT0cwu7wlaxoOFnU3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="TpIOFnU3y7vlILEv5ukU5cK" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <iq id="DvSmZ0m9veEqiJKN4l027hI" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="5c1IMOTOYnX8s2wbEBGzsWV" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: connected->disconnecting 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Terminating XMPP Stream 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq from="localhost" id="TqJQJvazAbDBeNIDl0mYdsg" xmlns="jabber:client" type="result" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <presence from="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq from="localhost" id="8ozLyslT0cwu7wlaxoOFnU3" xmlns="jabber:client" type="result" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__q4v47y0534@localhost/993927746-tigase-291 << <iq id="DvSmZ0m9veEqiJKN4l027hI" xmlns="jabber:client" to="jaxmpp__q4v47y0534@localhost/993927746-tigase-291" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3842] Connector state changed: disconnecting->disconnected |
| testStateAfterAuthTimeoutWebSocket | 20.351s |
2019-11-24 19:18:04 |
2019-11-24 19:18:04 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:18:04 | ------------------------------------ 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:04 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-24 19:18:04 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-24 19:18:04 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzEzcnI4YzA1MzUscj1LVDlqRWxLb3BBSkZFdlZPUDAwZQ==</auth> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1LVDlqRWxLb3BBSkZFdlZPUDAwZWdGQ2VGanNZaHFxU3VJdlYxYUZPLHM9THVJR1B5TWFXQWVVSGc9PSxpPTQwOTY=</challenge> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9S1Q5akVsS29wQUpGRXZWT1AwMGVnRkNlRmpzWWhxcVN1SXZWMWFGTyxwPVVjQkMxLzM4MlZJdW5UaDduQTlFTk9kNFdXRWlOS0NFc1NSODY2V2c3Qms9</response> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1SR1NxT0ZSSHVEcFZzUjdITnE2OW5VazRBbm1SamZhcUVJajJEM0pLeFhzPQ==</success> 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost >> <iq xmlns="jabber:client" id="8tEVS6DtawaMHGFOPYYZ1cD" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost >> <iq xmlns="jabber:client" id="1gZFs7jWeIHqcPRW4gY5sdU" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost >> <iq xmlns="jabber:client" id="SsxKw5dApgxl14t4lLQ2kVS" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <iq id="8tEVS6DtawaMHGFOPYYZ1cD" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-292" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__13rr8c0535@localhost/993927746-tigase-292</jid></bind></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <iq id="1gZFs7jWeIHqcPRW4gY5sdU" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-293" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__13rr8c0535@localhost/993927746-tigase-293</jid></bind></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost << <iq id="SsxKw5dApgxl14t4lLQ2kVS" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-294" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__13rr8c0535@localhost/993927746-tigase-294</jid></bind></iq> 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq xmlns="jabber:client" id="mtyMAdt6rkFBx6xcBhS6sbo" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq xmlns="jabber:client" id="jzOh2hSMBfExw3n5cJAnPYi" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq xmlns="jabber:client" id="KwDOKW1kXoZiChBmXmJJNCz" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 << <iq id="mtyMAdt6rkFBx6xcBhS6sbo" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-294" type="result"/> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 << <iq id="jzOh2hSMBfExw3n5cJAnPYi" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-294" type="result"/> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 << <iq id="KwDOKW1kXoZiChBmXmJJNCz" xmlns="jabber:client" to="jaxmpp__13rr8c0535@localhost/993927746-tigase-294" type="result"/> 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq to="localhost" id="exEQO4hQZs7y8jFudSLNVS5" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq to="localhost" id="nEXiDjMN25rdsfCrXeOS14u" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq to="localhost" id="YAJgxl14t4lLQ2kVS6Dtawa" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq id="QFWIDAcrV0fWT3rU9q3bGIG" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq id="7J6OTOYnX8s2wbEBGzsWVTW" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <iq id="bK71fICmateL0dKw5dApgxl" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-24 19:18:24 | jaxmpp_ :: jaxmpp__13rr8c0535@localhost/993927746-tigase-294 << <enabled max="60" location="d4270859fb27" resume="true" id="0c595fd2-9ed0-46be-ae3e-ed6e83c55f7e" xmlns="urn:xmpp:sm:3"/> 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-24 19:18:24 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected |
| testStateAfterConnectionFailureBosh | 0.846s |
2019-11-24 19:18:24 |
2019-11-24 19:18:24 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:18:24 | ------------------------------------ 2019-11-24 19:18:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2pjd2o2cDA1MzYscj1taTY2UTZRbGNzMmxHRE5MQUlzVg==</auth> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1taTY2UTZRbGNzMmxHRE5MQUlzVjFRd01pSTNGTFl1NG1INXdYT0JDLHM9WHNOYzNEamZRdm4wbFE9PSxpPTQwOTY=</challenge> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bWk2NlE2UWxjczJsR0ROTEFJc1YxUXdNaUkzRkxZdTRtSDV3WE9CQyxwPWpaY0NiTU9ra2ZhQTRFL1hFamg5VlVFSU5YWXArbnVWeU9BMGgxeHlnRjA9</response> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj11ZG9DLzMvTzFsTmM4aERWQkJkSExjc2IvUEhtQVdtR2p0cEhldUs0cEJZPQ==</success> 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost >> <iq xmlns="jabber:client" id="hUtraDKHIsXX4ncp5v2pW5f" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:25 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost/993927746-tigase-295 >> <iq to="localhost" id="QM3W5u039s9KvhTOX2hDAwi" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost/993927746-tigase-295 << <iq id="hUtraDKHIsXX4ncp5v2pW5f" xmlns="jabber:client" to="jaxmpp__jcwj6p0536@localhost/993927746-tigase-295" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jcwj6p0536@localhost/993927746-tigase-295</jid></bind></iq> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost/993927746-tigase-295 >> <iq id="dHM8s2wbEBGzsWVTW7Gn1nM" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:25 | jaxmpp_ :: jaxmpp__jcwj6p0536@localhost/993927746-tigase-295 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:18:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureSocket | 0.256s |
2019-11-24 19:18:26 |
2019-11-24 19:18:26 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:18:26 | ------------------------------------ 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: null->connecting 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] DNS entry stored in session object: missing:5222 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Preparing connection to [missing:5222] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] missing. Trying next. 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: connecting->disconnected 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: null->connecting 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] DNS entry stored in session object: localhost:5222 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Preparing connection to [localhost:5222] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: connecting->connected 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Whitespace ping period is setted to nullms 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><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-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3895] Start TLS 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3899] Proceeding TLS 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3899] Start handshake 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2M1djU2MDUzNyxyPWNCNkVlRFQwb0trY1Zoc1ZYaTlu</auth> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jQjZFZURUMG9La2NWaHNWWGk5bldiRUk1TmJ2T0Z5SUZtQkZudlpuLHM9RWwyTThUNFQzUHIrQ2c9PSxpPTQwOTY=</challenge> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y0I2RWVEVDBvS2tjVmhzVlhpOW5XYkVJNU5idk9GeUlGbUJGbnZabixwPWxYK2JYY1VnVzE2QksvalBJK2NGZHFMeFBmVjc2ZGVIeXFzZVFFM3hxUWM9</response> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1zKzZVRXFvbTdjdnJ1S2NHZHhYUUc5TDB4Q21BbHY2dG9hV2dlay9oakNzPQ==</success> 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost >> <iq xmlns="jabber:client" id="CSb4o38CFxiMGOLOVkR26fx" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost << <iq id="CSb4o38CFxiMGOLOVkR26fx" xmlns="jabber:client" to="jaxmpp__c5v560537@localhost/993927746-tigase-296" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__c5v560537@localhost/993927746-tigase-296</jid></bind></iq> 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 >> <iq xmlns="jabber:client" id="JMRiMGOLOVkR26fxeHQHFFm" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 << <iq id="JMRiMGOLOVkR26fxeHQHFFm" xmlns="jabber:client" to="jaxmpp__c5v560537@localhost/993927746-tigase-296" type="result"/> 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 >> <iq to="localhost" id="1ClnSXZiW8x6yxhMKCGviTg" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 << <enabled max="60" location="d4270859fb27" resume="true" id="c8e9d8d9-f276-431e-8aa5-5d3a36cc1b8e" xmlns="urn:xmpp:sm:3"/> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 >> <iq id="gJE6lYfY9Cgzbwh01jXWdyj" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@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-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 << <iq from="localhost" id="1ClnSXZiW8x6yxhMKCGviTg" xmlns="jabber:client" type="result" to="jaxmpp__c5v560537@localhost/993927746-tigase-296"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10589/f55efd39" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 << <iq id="gJE6lYfY9Cgzbwh01jXWdyj" xmlns="jabber:client" to="jaxmpp__c5v560537@localhost/993927746-tigase-296" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: connected->disconnecting 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Terminating XMPP Stream 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3899] Connector state changed: disconnecting->disconnected 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__c5v560537@localhost/993927746-tigase-296 << <presence from="jaxmpp__c5v560537@localhost/993927746-tigase-296" xmlns="jabber:client" to="jaxmpp__c5v560537@localhost"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureWebSocket | 0.356s |
2019-11-24 19:18:26 |
2019-11-24 19:18:26 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-24 19:18:26 | ------------------------------------ 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><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><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-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3VjODBhYjA1Mzgscj1LaE9XWWd4WHVrVzZ1bERuUFRUYw==</auth> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1LaE9XWWd4WHVrVzZ1bERuUFRUY1ZnamN4NkpoTnVqdzNUcXVHVk1ELHM9c1Zyc1M0WVphS2p4MWc9PSxpPTQwOTY=</challenge> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9S2hPV1lneFh1a1c2dWxEblBUVGNWZ2pjeDZKaE51anczVHF1R1ZNRCxwPXNoYXJaVjFJd3hEbkE0T2tWVjdEQ3BrNGNFTzJLY041cDEyWm9BUjJSQkE9</response> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1BWVBoVmFSQkczS0V6Y2cwRFBLTkFoSWRPeHV3YzVzeloxNk1mVVJyb2c0PQ==</success> 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="7etRvi08c1DSCAgh81QLOpuANqY="/></features> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost >> <iq xmlns="jabber:client" id="YU3UPVbo7mVVaGCv2qhLKGu" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost << <iq id="YU3UPVbo7mVVaGCv2qhLKGu" xmlns="jabber:client" to="jaxmpp__uc80ab0538@localhost/993927746-tigase-297" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__uc80ab0538@localhost/993927746-tigase-297</jid></bind></iq> 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost/993927746-tigase-297 >> <iq xmlns="jabber:client" id="JTYweACn2neFEkHr2j49ztY" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost/993927746-tigase-297 << <iq id="JTYweACn2neFEkHr2j49ztY" xmlns="jabber:client" to="jaxmpp__uc80ab0538@localhost/993927746-tigase-297" type="result"/> 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost/993927746-tigase-297 >> <iq to="localhost" id="n1k27hIFMzp9kZ6pT6ymZ0m" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost/993927746-tigase-297 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@localhost/993927746-tigase-297 >> <iq id="9ZcMUfHCqYoV8zCrcqTPORa" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-24 19:18:26 | jaxmpp_ :: jaxmpp__uc80ab0538@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-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-24 19:18:26 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-24 19:18:26 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-24 19:18:29 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |