Test duration: 64.700s
| Passed Tests | ||
|---|---|---|
| tigase.tests.jaxmpp.TestHandlingOfConnectionIssues | ||
| testStateAfterAuthTimeoutBosh | 20.602s |
2019-11-27 20:02:35 |
2019-11-27 20:02:35 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:02:35 | ------------------------------------ 2019-11-27 20:02:35 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:35 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:02:35 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:02:35 | jaxmpp_ :: jaxmpp__4hemd20531@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-27 20:02:55 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@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-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRoZW1kMjA1MzEscj1lSVdwenE5cWhFZ1hNY2d5ZEdvYg==</auth> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1lSVdwenE5cWhFZ1hNY2d5ZEdvYmYwN1p0YkRDaWhYTTJURk50U0FSLHM9Mkg5Zmc5M1BxRm9pWEE9PSxpPTQwOTY=</challenge> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZUlXcHpxOXFoRWdYTWNneWRHb2JmMDdadGJEQ2loWE0yVEZOdFNBUixwPXZ5dlk1ZEp6UXJORHBvcmh3UHZrbC90dkpXMWM1SjROTkdja1NTTlFnc3M9</response> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13SG9GcDExNkliSS9tMWZwbFVwMGVqT0lEQXRxTUxCOUJMcnJudHJqUHZBPQ==</success> 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost >> <iq xmlns="jabber:client" id="ofN8j24xwiQO3zvasaNEy8j" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost >> <iq xmlns="jabber:client" id="yfLm5kMO5eIsX0akR7j4nRX" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost >> <iq xmlns="jabber:client" id="xXRwvnUW8JwiChEsU1rXmX1" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost << <iq id="ofN8j24xwiQO3zvasaNEy8j" to="jaxmpp__4hemd20531@localhost/993927746-tigase-287" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hemd20531@localhost/993927746-tigase-287</jid></bind></iq> 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-287 >> <iq to="localhost" id="p11skEHQW8odT9s2k7iQIt2" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-287 >> <iq id="bTBCkOCrhDIFr7ISVTcobOS" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-287 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-287 << <iq id="yfLm5kMO5eIsX0akR7j4nRX" to="jaxmpp__4hemd20531@localhost/993927746-tigase-288" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hemd20531@localhost/993927746-tigase-288</jid></bind></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-287 << <iq id="xXRwvnUW8JwiChEsU1rXmX1" to="jaxmpp__4hemd20531@localhost/993927746-tigase-289" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4hemd20531@localhost/993927746-tigase-289</jid></bind></iq> 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 >> <iq to="localhost" id="sSwO4o2cP7BmQWlKKw5gGmV" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 << <iq id="bTBCkOCrhDIFr7ISVTcobOS" to="jaxmpp__4hemd20531@localhost/993927746-tigase-289" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 >> <iq to="localhost" id="rjNXj4o2cP7BmQWlKKw5gGm" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$2.run: 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 >> <iq id="hjLYo19xyyhBCeRaAqYndNK" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 << <presence to="jaxmpp__4hemd20531@localhost" xmlns="jabber:client" from="jaxmpp__4hemd20531@localhost/993927746-tigase-289"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza 2019-11-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 << <iq id="sSwO4o2cP7BmQWlKKw5gGmV" to="jaxmpp__4hemd20531@localhost/993927746-tigase-289" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:02:55 | jaxmpp_ :: jaxmpp__4hemd20531@localhost/993927746-tigase-289 << <iq id="rjNXj4o2cP7BmQWlKKw5gGm" to="jaxmpp__4hemd20531@localhost/993927746-tigase-289" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:02:55 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-27 20:02:55 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-27 20:02:55 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 |
| testStateAfterAuthTimeoutSocket | 20.275s |
2019-11-27 20:02:56 |
2019-11-27 20:02:56 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:02:56 | ------------------------------------ 2019-11-27 20:02:56 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] Connector state changed: null->connecting 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] DNS entry stored in session object: localhost:5222 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] Preparing connection to [localhost:5222] 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] Connector state changed: connecting->connected 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=1] Whitespace ping period is setted to nullms 2019-11-27 20:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:56 | jaxmpp_ :: jaxmpp__6ynrjr0532@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-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=3832] Start TLS 2019-11-27 20:02:56 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=3834] Proceeding TLS 2019-11-27 20:02:56 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=3834] Start handshake 2019-11-27 20:02:56 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:02:56 | jaxmpp_ :: jaxmpp__6ynrjr0532@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-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=3834] Connector state changed: connected->disconnected 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=592 thread=3834] Stream terminated 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: null->connecting 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] DNS entry stored in session object: localhost:5222 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Preparing connection to [localhost:5222] 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: connecting->connected 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Whitespace ping period is setted to nullms 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@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-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3832] Start TLS 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3839] Proceeding TLS 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3839] Start handshake 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@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-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzZ5bnJqcjA1MzIscj0yR1dyQ3dQZWhnZkhnVkkwb0h2dA==</auth> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0yR1dyQ3dQZWhnZkhnVkkwb0h2dHVFcHJZMFlZUVJJbnE3MnpraVlSLHM9TmNvSnR1eG9RTjVpWmc9PSxpPTQwOTY=</challenge> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MkdXckN3UGVoZ2ZIZ1ZJMG9IdnR1RXByWTBZWVFSSW5xNzJ6a2lZUixwPWpwN2hSUFhoVDRxcUUreENRRGdHem8wWVFpT1dxTDNGUjVMWmhpVCtQNlU9</response> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1WMzh1b2lWWEcxVWxqTFY3Q2xVc0lYT253L1dXYUg2YTArTG9VS0FkUStJPQ==</success> 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <iq xmlns="jabber:client" id="rpT9HQW8odT9s2k7iQIt2es" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost >> <iq xmlns="jabber:client" id="ZpRxkUWj5xeAqjGCgzeCeFJ" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost << <iq id="rpT9HQW8odT9s2k7iQIt2es" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-290" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__6ynrjr0532@localhost/993927746-tigase-290</jid></bind></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost << <iq id="ZpRxkUWj5xeAqjGCgzeCeFJ" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-291" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__6ynrjr0532@localhost/993927746-tigase-291</jid></bind></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-291 >> <iq xmlns="jabber:client" id="3u5TW0gZ2kGpUYW5zdSZ5pi" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-291 >> <iq xmlns="jabber:client" id="vityeTfRSS0t0gy9oWSXjHQ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-291 << <iq id="3u5TW0gZ2kGpUYW5zdSZ5pi" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__6ynrjr0532@localhost/993927746-tigase-292</jid></bind></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-291 >> <iq xmlns="jabber:client" id="L3IZnP6rZdUgHriAzAq6k5D" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-291 << <iq id="vityeTfRSS0t0gy9oWSXjHQ" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq xmlns="jabber:client" id="kjsnZsmTP48FMOSSbP1cwi3" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq to="localhost" id="Ey9IGoPKEiNZ3ylNP8IBfUO" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="L3IZnP6rZdUgHriAzAq6k5D" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="kjsnZsmTP48FMOSSbP1cwi3" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq id="I9UgGmVfzApV5EKFnak3ymY" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <enabled id="c15f41ec-455b-4f09-86ec-6dce7446ef1d" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq to="localhost" id="NwZpgLDvuq15AaJU4oUfXhI" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="Ey9IGoPKEiNZ3ylNP8IBfUO" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq to="localhost" id="qbWEiZq6s2vtjMGCHsamLCo" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq id="s9OncEyeTfRSS0t0gy9oWSX" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="I9UgGmVfzApV5EKFnak3ymY" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <iq id="KkjY9Hto19xyyhBCeRaAqYn" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <presence to="jaxmpp__6ynrjr0532@localhost" xmlns="jabber:client" from="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: connected->disconnecting 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Terminating XMPP Stream 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="s9OncEyeTfRSS0t0gy9oWSX" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="NwZpgLDvuq15AaJU4oUfXhI" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="KkjY9Hto19xyyhBCeRaAqYn" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <iq id="qbWEiZq6s2vtjMGCHsamLCo" to="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:03:16 | jaxmpp_ :: jaxmpp__6ynrjr0532@localhost/993927746-tigase-292 << <presence to="jaxmpp__6ynrjr0532@localhost" xmlns="jabber:client" from="jaxmpp__6ynrjr0532@localhost/993927746-tigase-292"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3839] Connector state changed: disconnecting->disconnected |
| testStateAfterAuthTimeoutWebSocket | 20.389s |
2019-11-27 20:03:16 |
2019-11-27 20:03:16 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:03:16 | ------------------------------------ 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-27 20:03:16 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-27 20:03:16 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:16 | jaxmpp_ :: jaxmpp__byez3w0533@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-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:36 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-27 20:03:36 | jaxmpp_ :: jaxmpp__byez3w0533@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-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:03:36 | jaxmpp_ :: jaxmpp__byez3w0533@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2J5ZXozdzA1MzMscj1pajNHazBRRG5FZ3V5dEJQNUZCeA==</auth> 2019-11-27 20:03:36 | jaxmpp_ :: jaxmpp__byez3w0533@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1pajNHazBRRG5FZ3V5dEJQNUZCeDFkeTdFZjMySExtUEduOWxFdjdRLHM9QnhkRGdPT0xCUXF0Q3c9PSxpPTQwOTY=</challenge> 2019-11-27 20:03:36 | jaxmpp_ :: jaxmpp__byez3w0533@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aWozR2swUURuRWd1eXRCUDVGQngxZHk3RWYzMkhMbVBHbjlsRXY3USxwPUZsK2lYWTZaSmdRTzg1SHZXa3Y4VGVnMlFmYUVUWjdwbitIbmt2U3JodjQ9</response> 2019-11-27 20:03:36 | jaxmpp_ :: jaxmpp__byez3w0533@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1xYXNRUzFwc2lidDRhcE5IdHJoN0NyMkltazk4TlJBU2MyTWkvUk5SUG1zPQ==</success> 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:36 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost >> <iq xmlns="jabber:client" id="Rle3ACpR8gViQW0rl3gEyzc" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost >> <iq xmlns="jabber:client" id="Ii3ygwfTRazfJWkPHOCDIGC" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost >> <iq xmlns="jabber:client" id="Fk7QIt2es7Dl8v7tqiLzy6B" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost << <iq id="Rle3ACpR8gViQW0rl3gEyzc" to="jaxmpp__byez3w0533@localhost/993927746-tigase-293" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__byez3w0533@localhost/993927746-tigase-293</jid></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost << <iq id="Ii3ygwfTRazfJWkPHOCDIGC" to="jaxmpp__byez3w0533@localhost/993927746-tigase-294" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__byez3w0533@localhost/993927746-tigase-294</jid></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost << <iq id="Fk7QIt2es7Dl8v7tqiLzy6B" to="jaxmpp__byez3w0533@localhost/993927746-tigase-295" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__byez3w0533@localhost/993927746-tigase-295</jid></bind></iq> 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq xmlns="jabber:client" id="8Kl6sWVW24l5fW7lVk6nN00" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq xmlns="jabber:client" id="cl6EsU1rXmX1h2wdtmIP6sa" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq xmlns="jabber:client" id="cm7Gw2hXZq5hN4AlJSKTeIG" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 << <iq id="8Kl6sWVW24l5fW7lVk6nN00" to="jaxmpp__byez3w0533@localhost/993927746-tigase-295" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 << <iq id="cl6EsU1rXmX1h2wdtmIP6sa" to="jaxmpp__byez3w0533@localhost/993927746-tigase-295" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 << <iq id="cm7Gw2hXZq5hN4AlJSKTeIG" to="jaxmpp__byez3w0533@localhost/993927746-tigase-295" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq to="localhost" id="ipegLDvuq15AaJU4oUfXhIy" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq to="localhost" id="KvukR7j4nRXoeDj24xwiQO3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$2.run: 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq to="localhost" id="Jsj8gJRN2jJHSczbMyvsaAi" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.eventbus.ThreadSafeEventBus$2.run: 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 >> <iq id="Bxwwi3aAcrY6cBBh0fBrmav" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__byez3w0533@localhost/993927746-tigase-295 << <enabled id="32152ccb-0c22-4e4d-ae18-eb72c9b82619" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.presence.PresenceModule$3.after: Could not write stanza 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2019-11-27 20:03:40 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |
| testStateAfterConnectionFailureBosh | 0.761s |
2019-11-27 20:03:37 |
2019-11-27 20:03:37 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:03:37 | ------------------------------------ 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@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-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3ltcGVxaTA1MzQscj1WN2daZUFWajlNRlp2dUJqbWRHYQ==</auth> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1WN2daZUFWajlNRlp2dUJqbWRHYWZKUXVKbHpnOFdlY1puTFRrd3FVLHM9cTNqdDZEWWtFYkFmNHc9PSxpPTQwOTY=</challenge> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VjdnWmVBVmo5TUZadnVCam1kR2FmSlF1Smx6ZzhXZWNabkxUa3dxVSxwPTRDVXVTd3RFM3JieDUzbGMraGt6QnJnQnpmWFhvTHB4V2NlTGVMb3Q1VkE9</response> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1SbWJ6SVU5NUdsQU9sWndJMmx2bnBTUm5yNVFSVnR5SnRyRTFneWF2c2E0PQ==</success> 2019-11-27 20:03:37 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost >> <iq xmlns="jabber:client" id="gGLhDIFr7ISVTcobOS08HR5" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost << <iq id="gGLhDIFr7ISVTcobOS08HR5" to="jaxmpp__ympeqi0534@localhost/993927746-tigase-296" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__ympeqi0534@localhost/993927746-tigase-296</jid></bind></iq> 2019-11-27 20:03:37 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost/993927746-tigase-296 >> <iq to="localhost" id="wKVk5DDqYcRMOElMIq3v5Ct" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@localhost/993927746-tigase-296 >> <iq id="KFEEvpTeHvfYoXXkEHQW8od" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:37 | jaxmpp_ :: jaxmpp__ympeqi0534@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-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureSocket | 0.230s |
2019-11-27 20:03:38 |
2019-11-27 20:03:38 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:03:38 | ------------------------------------ 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=597 thread=1] Connector state changed: null->connecting 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=597 thread=1] DNS entry stored in session object: missing:5222 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=597 thread=1] Preparing connection to [missing:5222] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=597 thread=1] missing. Trying next. 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=597 thread=1] Connector state changed: connecting->disconnected 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: null->connecting 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] DNS entry stored in session object: localhost:5222 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Preparing connection to [localhost:5222] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Opening connection to localhost/127.0.0.1:5222 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: connecting->connected 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Whitespace ping period is setted to nullms 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@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-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=3889] Start TLS 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=3893] Proceeding TLS 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=3893] Start handshake 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@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-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3N1NzloMDUzNSxyPXFzUHd2VmNpdUhta3VRUzlHS0xN</auth> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1xc1B3dlZjaXVIbWt1UVM5R0tMTWpJd0VvdzVQNXR4bkpmU01lc2hzLHM9VGhyUW40R0hyYzlvNWc9PSxpPTQwOTY=</challenge> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cXNQd3ZWY2l1SG1rdVFTOUdLTE1qSXdFb3c1UDV0eG5KZlNNZXNocyxwPUJldmZVWWtyb0JUYXpGVjRxdXI3VktabzBySldPNk5ua0pRbCsyQnJQb1U9</response> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj02Z2wwRGwyMjdER2FWZzhjOGpYS1NVY3Bnbit0ZE1WK0ordDZlZWxZZno4PQ==</success> 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost >> <iq xmlns="jabber:client" id="szedS0gEJVdAiQ9mYYZ4iAn" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost << <iq id="szedS0gEJVdAiQ9mYYZ4iAn" to="jaxmpp__su79h0535@localhost/993927746-tigase-297" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__su79h0535@localhost/993927746-tigase-297</jid></bind></iq> 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 >> <iq xmlns="jabber:client" id="mPYM0bpesj4o2cP7BmQWlKK" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 << <iq id="mPYM0bpesj4o2cP7BmQWlKK" to="jaxmpp__su79h0535@localhost/993927746-tigase-297" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 >> <iq to="localhost" id="7R2TQWUROMyj17GBjRcQRLW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 << <enabled id="9aea0105-e8b3-4e54-a9a8-004f987b9315" max="60" resume="true" location="d4270859fb27" xmlns="urn:xmpp:sm:3"/> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 >> <iq id="MPUzdSZ5pi2fxuYl8wiIRbJ" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@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-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 << <iq id="7R2TQWUROMyj17GBjRcQRLW" to="jaxmpp__su79h0535@localhost/993927746-tigase-297" xmlns="jabber:client" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component" type="router"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="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-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 << <iq id="MPUzdSZ5pi2fxuYl8wiIRbJ" to="jaxmpp__su79h0535@localhost/993927746-tigase-297" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: connected->disconnecting 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Terminating XMPP Stream 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__su79h0535@localhost/993927746-tigase-297 << <presence to="jaxmpp__su79h0535@localhost" xmlns="jabber:client" from="jaxmpp__su79h0535@localhost/993927746-tigase-297"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=3893] Connector state changed: disconnecting->disconnected 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureWebSocket | 0.352s |
2019-11-27 20:03:38 |
2019-11-27 20:03:38 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2019-11-27 20:03:38 | ------------------------------------ 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@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-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3pkMmlxdTA1MzYscj1RV0VUZ0VXRll6Rno5MHdVY2J3eQ==</auth> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1RV0VUZ0VXRll6Rno5MHdVY2J3eTlkYTZXajVnV0s1OUQxNkRGZkx1LHM9V2NIRTZlcU5GR3E5eFE9PSxpPTQwOTY=</challenge> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9UVdFVGdFV0ZZekZ6OTB3VWNid3k5ZGE2V2o1Z1dLNTlEMTZERmZMdSxwPUxUdG4zYlEvZlhxRzg1L1ZRamhac0NTT0dRTldkYzF3RG9WR1BaWVlCbVk9</response> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1QNjJTU1g3WEc3MU8zR3RXSGlPK3k2UHEvTjl3emdhemRHTUZiL2lEdlhNPQ==</success> 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@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="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost >> <iq xmlns="jabber:client" id="j8HZ5pi2fxuYl8wiIRbJCmY" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost << <iq id="j8HZ5pi2fxuYl8wiIRbJCmY" to="jaxmpp__zd2iqu0536@localhost/993927746-tigase-298" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zd2iqu0536@localhost/993927746-tigase-298</jid></bind></iq> 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost/993927746-tigase-298 >> <iq xmlns="jabber:client" id="ROHZsmTP48FMOSSbP1cwi3a" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost/993927746-tigase-298 << <iq id="ROHZsmTP48FMOSSbP1cwi3a" to="jaxmpp__zd2iqu0536@localhost/993927746-tigase-298" xmlns="jabber:client" type="result"/> 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost/993927746-tigase-298 >> <iq to="localhost" id="r8DCDIGCm9LTW0gZ2kGpUYW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost/993927746-tigase-298 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@localhost/993927746-tigase-298 >> <iq id="7Za9mYYZ4iAn9ncDnZsmTP4" type="get"><query xmlns="jabber:iq:roster"/></iq> 2019-11-27 20:03:38 | jaxmpp_ :: jaxmpp__zd2iqu0536@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-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2019-11-27 20:03:38 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2019-11-27 20:03:38 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2019-11-27 20:03:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2019-11-27 20:03:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |