Test duration: 65.018s
| Passed Tests | ||
|---|---|---|
| tigase.tests.jaxmpp.TestHandlingOfConnectionIssues | ||
| testStateAfterAuthTimeoutBosh | 20.673s |
2020-01-02 19:57:01 |
2020-01-02 19:57:01 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:57:01 | ------------------------------------ 2020-01-02 19:57:01 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:57:01 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:57:01 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2020-01-02 19:57:02 | jaxmpp_ :: jaxmpp__3igw4r0533@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> 2020-01-02 19:57:21 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@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> 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzNpZ3c0cjA1MzMscj1jTVFySWZ1dHpkWFczYmVXUXhTWQ==</auth> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jTVFySWZ1dHpkWFczYmVXUXhTWXRaYVU1U29WUElzcjZ5UW5XRkgwLHM9cDNkVklqdGowUWZta0E9PSxpPTQwOTY=</challenge> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y01RcklmdXR6ZFhXM2JlV1F4U1l0WmFVNVNvVlBJc3I2eVFuV0ZIMCxwPWpLZG4zcG9WTXB5S25GZmRjbjJOZ1Y2bStWRFh6M0E5RXdUQXN4ejRCalU9</response> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj16SUM0M3RiaG5Bd3d6TjkzN3YxTFZYNHhMZElLdVBqTXhUMDN1SEtyV20wPQ==</success> 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost >> <iq xmlns="jabber:client" id="x5bLLMAFLPT2dzp9i3gAs48" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost >> <iq xmlns="jabber:client" id="H1Xq7z8JCiSUOVapS17Duo8" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost >> <iq xmlns="jabber:client" id="LTxUY0iKuhSPIHtlUeOJGo1" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-287 >> <iq to="localhost" id="k42rbGrW9n9wsgDu0fKwxvj" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-287 << <iq id="H1Xq7z8JCiSUOVapS17Duo8" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-287" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3igw4r0533@localhost/943755961-tigase-287</jid></bind></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-287 << <iq id="x5bLLMAFLPT2dzp9i3gAs48" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-288" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3igw4r0533@localhost/943755961-tigase-288</jid></bind></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-288 >> <iq id="skM9IMFy8vgyBDzn09r9DgZ" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-288 << <iq id="LTxUY0iKuhSPIHtlUeOJGo1" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__3igw4r0533@localhost/943755961-tigase-289</jid></bind></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-288 >> <iq to="localhost" id="7nTs9yfDxpO5kIIAaDAfynP" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <iq id="Jeqy6Fu2mSKNUTQXZnYm4fI" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <iq to="localhost" id="snPfUar6eHEk9JLLMHxl12x" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <iq id="O3PjMyp8lKLBBqgSRWU3mKZ" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 << <iq id="k42rbGrW9n9wsgDu0fKwxvj" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 << <iq id="skM9IMFy8vgyBDzn09r9DgZ" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 << <iq id="7nTs9yfDxpO5kIIAaDAfynP" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 << <iq id="Jeqy6Fu2mSKNUTQXZnYm4fI" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 << <iq id="snPfUar6eHEk9JLLMHxl12x" xmlns="jabber:client" to="jaxmpp__3igw4r0533@localhost/943755961-tigase-289" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__3igw4r0533@localhost/943755961-tigase-289 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 |
| testStateAfterAuthTimeoutSocket | 20.278s |
2020-01-02 19:57:22 |
2020-01-02 19:57:22 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:57:22 | ------------------------------------ 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: null->connecting 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] DNS entry stored in session object: localhost:5222 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Preparing connection to [localhost:5222] 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Connector state changed: connecting->connected 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=1] Whitespace ping period is setted to nullms 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__jbzex70534@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> 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3836] Start TLS 2020-01-02 19:57:22 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3838] Proceeding TLS 2020-01-02 19:57:22 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3838] Start handshake 2020-01-02 19:57:22 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:23 | jaxmpp_ :: jaxmpp__jbzex70534@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> 2020-01-02 19:57:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3838] Connector state changed: connected->disconnected 2020-01-02 19:57:42 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=593 thread=3838] Stream terminated 2020-01-02 19:57:42 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:57:42 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: null->connecting 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] DNS entry stored in session object: localhost:5222 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Preparing connection to [localhost:5222] 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: connecting->connected 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Whitespace ping period is setted to nullms 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@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> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3836] Start TLS 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3843] Proceeding TLS 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3843] Start handshake 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@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> 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2piemV4NzA1MzQscj15Nm9vM25vVnRGcmk3Z2R2WEN4Uw==</auth> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15Nm9vM25vVnRGcmk3Z2R2WEN4U1VyY1BkOVRWRVAzWkNHZHdHSnp0LHM9WXhQdVJTQUNzSlhKYUE9PSxpPTQwOTY=</challenge> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eTZvbzNub1Z0RnJpN2dkdlhDeFNVcmNQZDlUVkVQM1pDR2R3R0p6dCxwPWMxaC9pd2JRRWZVVHptNEFGekI4bmNuQW80UmdEVm1LM3dXamFmanFnaDg9</response> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj01UWhOdHVCUW44dzhNcm1aalIwM3pVNnc2cUxISVcwUVVIUVVyb25QOFdzPQ==</success> 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <iq xmlns="jabber:client" id="EmDRY5jT7eV04q00jWXYdvp" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <iq xmlns="jabber:client" id="akvv9ztWlQKMIq9CjGDl5oc" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost << <iq id="EmDRY5jT7eV04q00jWXYdvp" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-290" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jbzex70534@localhost/943755961-tigase-290</jid></bind></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost >> <iq xmlns="jabber:client" id="uv0IxiCtcql7xjSTV7z8JCi" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-290 >> <iq xmlns="jabber:client" id="R6LbGrW9n9wsgDu0fKwxvjR" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-290 << <iq id="akvv9ztWlQKMIq9CjGDl5oc" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-291" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jbzex70534@localhost/943755961-tigase-291</jid></bind></iq> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-291 >> <iq xmlns="jabber:client" id="mqH4dufzxi0mPSMNQIVhRXa" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-291 << <iq id="uv0IxiCtcql7xjSTV7z8JCi" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jbzex70534@localhost/943755961-tigase-292</jid></bind></iq> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq xmlns="jabber:client" id="3nwEk9JLLMHxl12xewkOCxo" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="R6LbGrW9n9wsgDu0fKwxvjR" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result"/> 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq to="localhost" id="xnumXamdR1hOLFBx4BmMQQG" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq id="QkjYfOHsbrhMYiHCgLMAFLP" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="mqH4dufzxi0mPSMNQIVhRXa" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result"/> 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq to="localhost" id="ckhbHofEm0akXex8x9HCEEp" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <enabled id="57a3280a-0b05-4730-afc1-7a177533836c" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq id="GsDLFBx4BmMQQGFEnOTR7sk" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="3nwEk9JLLMHxl12xewkOCxo" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result"/> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="xnumXamdR1hOLFBx4BmMQQG" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq to="localhost" id="BfYN1lV6lPVbmbw6u8gPY16" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="QkjYfOHsbrhMYiHCgLMAFLP" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <iq id="kon6vkJwjHNO2qX8iPUQbNT" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <iq id="ckhbHofEm0akXex8x9HCEEp" xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost/943755961-tigase-292" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Connector state changed: connected->disconnecting 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=1] Terminating XMPP Stream 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3843] Connector state changed: disconnecting->disconnected 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=594 thread=3843] Stream terminated 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__jbzex70534@localhost/943755961-tigase-292 << <presence xmlns="jabber:client" to="jaxmpp__jbzex70534@localhost" from="jaxmpp__jbzex70534@localhost/943755961-tigase-292"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |
| testStateAfterAuthTimeoutWebSocket | 20.365s |
2020-01-02 19:57:43 |
2020-01-02 19:57:43 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:57:43 | ------------------------------------ 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2020-01-02 19:57:43 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2020-01-02 19:57:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:57:43 | jaxmpp_ :: jaxmpp__xr72sh0535@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> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@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> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3hyNzJzaDA1MzUscj1xaU51cXBRaU1ZWlA2UlRBeWI1eQ==</auth> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1xaU51cXBRaU1ZWlA2UlRBeWI1eUNqSjQ1Q3cwTVo4R3haOTZXT0M3LHM9WDMyOUJadU9RY3pJb1E9PSxpPTQwOTY=</challenge> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cWlOdXFwUWlNWVpQNlJUQXliNXlDako0NUN3ME1aOEd4Wjk2V09DNyxwPU5oTHNsRWJwTlFaOS9lZ2JFS1c5RktRWWxnZVMxNTE3ckxzeFBVZkhzQ289</response> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1WUUZXZjY0WUthc08xeUZOQlR5aXlNczBrKzdUajh4NlNvT0N3eTdsZEhnPQ==</success> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost >> <iq xmlns="jabber:client" id="oncOTR7sk07sW7u0vrbBs3f" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost >> <iq xmlns="jabber:client" id="yl4JIspfNPUX3n5aNKyoWS3" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost >> <iq xmlns="jabber:client" id="Bm51tfJIzhPZYpY0iKuhSPI" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <iq id="oncOTR7sk07sW7u0vrbBs3f" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-293" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__xr72sh0535@localhost/943755961-tigase-293</jid></bind></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <iq id="yl4JIspfNPUX3n5aNKyoWS3" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-294" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__xr72sh0535@localhost/943755961-tigase-294</jid></bind></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost << <iq id="Bm51tfJIzhPZYpY0iKuhSPI" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-295" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__xr72sh0535@localhost/943755961-tigase-295</jid></bind></iq> 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq xmlns="jabber:client" id="tzG8ClUQV6eXlZdGv9ztWlQ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq xmlns="jabber:client" id="XrghMYiHCgLMAFLPT2dzp9i" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq xmlns="jabber:client" id="dERpO5kIIAaDAfynP3k4u5o" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 << <iq id="tzG8ClUQV6eXlZdGv9ztWlQ" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-295" type="result"/> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 << <iq id="XrghMYiHCgLMAFLPT2dzp9i" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-295" type="result"/> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 << <iq id="dERpO5kIIAaDAfynP3k4u5o" xmlns="jabber:client" to="jaxmpp__xr72sh0535@localhost/943755961-tigase-295" type="result"/> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq to="localhost" id="eJ4ur28ygwl05eJxtaKQNOE" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq to="localhost" id="rujnYm4fIs9y8LXdFAjLNVQ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq to="localhost" id="bzwxl12xewkOCxoRMPJRWiW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq id="xFMW7u0vrbBs3fLRITWWauY" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq id="BudhZhNQPNR36Fu2mSKNUTQ" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <iq id="SM3MDkVVfIQ2bAcDw4DHHDw" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:58:03 | jaxmpp_ :: jaxmpp__xr72sh0535@localhost/943755961-tigase-295 << <enabled id="185dc30a-4baa-4191-8c18-0614810c7d78" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/> 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-01-02 19:58:03 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:58:03 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected |
| testStateAfterConnectionFailureBosh | 0.749s |
2020-01-02 19:58:04 |
2020-01-02 19:58:04 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:58:04 | ------------------------------------ 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@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> 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2gzazEyYTA1MzYscj1YRENtbGRBVTJDeXMxNmZtNlduUw==</auth> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1YRENtbGRBVTJDeXMxNmZtNlduU0d2Z2NabVp3b0dnR3JUUmVYck94LHM9Nk5pQ2pUK1VCa3JGeUE9PSxpPTQwOTY=</challenge> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WERDbWxkQVUyQ3lzMTZmbTZXblNHdmdjWm1ad29HZ0dyVFJlWHJPeCxwPUNYRWYrRFhIK1EwaitIQzlqNy9wUk1SbVlUQjBMYW55Y3dSd0k1emFGd2s9</response> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1HTWxSQTFUSDlubmRwU1dCKzZsWTlOald4UGF6c1AwZTM1N2hod1FMSWZ3PQ==</success> 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost >> <iq xmlns="jabber:client" id="BzirjV5qZ7vlGPOGEsU7ybq" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:04 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost/943755961-tigase-296 >> <iq to="localhost" id="hIHOUYWbGrW9n9wsgDu0fKw" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost/943755961-tigase-296 << <iq id="BzirjV5qZ7vlGPOGEsU7ybq" xmlns="jabber:client" to="jaxmpp__h3k12a0536@localhost/943755961-tigase-296" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__h3k12a0536@localhost/943755961-tigase-296</jid></bind></iq> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost/943755961-tigase-296 >> <iq id="D0xJHEr1jXUhIugGm1mNEzd" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost/943755961-tigase-296 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:04 | jaxmpp_ :: jaxmpp__h3k12a0536@localhost/943755961-tigase-296 << <iq id="hIHOUYWbGrW9n9wsgDu0fKw" xmlns="jabber:client" to="jaxmpp__h3k12a0536@localhost/943755961-tigase-296" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:58:04 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureSocket | 0.257s |
2020-01-02 19:58:05 |
2020-01-02 19:58:05 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:58:05 | ------------------------------------ 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: null->connecting 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] DNS entry stored in session object: missing:5222 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Preparing connection to [missing:5222] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] missing. Trying next. 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=598 thread=1] Connector state changed: connecting->disconnected 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: null->connecting 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] DNS entry stored in session object: localhost:5222 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Preparing connection to [localhost:5222] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: connecting->connected 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Whitespace ping period is setted to nullms 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@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> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3894] Start TLS 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3898] Proceeding TLS 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3898] Start handshake 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@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> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2twbDc0dTA1Mzcscj1uZ1E3Vm5aNEpnR1RxMW5GcDZCdw==</auth> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1uZ1E3Vm5aNEpnR1RxMW5GcDZCd1Awc0xkeXdUYTVzMThmQ2Q3bm4xLHM9MGtjTThpR1BEa3lpSnc9PSxpPTQwOTY=</challenge> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bmdRN1ZuWjRKZ0dUcTFuRnA2QndQMHNMZHl3VGE1czE4ZkNkN25uMSxwPUxmektIOFVjZG4wOTdXcUpvNmR6SGpHeUthcWVnWUMrWkh3c25YRFF3bUE9</response> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1TZDRDMjU2Q3Z2RzZsWkJNallHbU1ieUU2Q055S0dxaUdhcUsyTFgvTmxRPQ==</success> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost >> <iq xmlns="jabber:client" id="AT4wxvjRY5jT7eV04q00jWX" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost << <iq id="AT4wxvjRY5jT7eV04q00jWX" xmlns="jabber:client" to="jaxmpp__kpl74u0537@localhost/943755961-tigase-297" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__kpl74u0537@localhost/943755961-tigase-297</jid></bind></iq> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 >> <iq xmlns="jabber:client" id="bNKZfPELGq8qQZiFo3bLKUa" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 << <iq id="bNKZfPELGq8qQZiFo3bLKUa" xmlns="jabber:client" to="jaxmpp__kpl74u0537@localhost/943755961-tigase-297" type="result"/> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 >> <iq to="localhost" id="oKzynP3k4u5oORYYrjV5qZ7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 >> <iq id="iKxIAaDAfynP3k4u5oORYYr" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 << <enabled id="f3fa7145-10d8-4140-9ac7-eb175734bd78" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 << <iq id="oKzynP3k4u5oORYYrjV5qZ7" xmlns="jabber:client" to="jaxmpp__kpl74u0537@localhost/943755961-tigase-297" type="result" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10616/8871f720"/><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="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 << <iq id="iKxIAaDAfynP3k4u5oORYYr" xmlns="jabber:client" to="jaxmpp__kpl74u0537@localhost/943755961-tigase-297" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Connector state changed: connected->disconnecting 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=1] Terminating XMPP Stream 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__kpl74u0537@localhost/943755961-tigase-297 << <presence xmlns="jabber:client" to="jaxmpp__kpl74u0537@localhost" from="jaxmpp__kpl74u0537@localhost/943755961-tigase-297"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=599 thread=3898] Connector state changed: disconnecting->disconnected 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] |
| testStateAfterConnectionFailureWebSocket | 0.299s |
2020-01-02 19:58:05 |
2020-01-02 19:58:05 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues] 2020-01-02 19:58:05 | ------------------------------------ 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session] 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector. 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@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> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2h2ZWVwdjA1Mzgscj1jU21NMFlHcHpSZUpVQlhGRW1VcA==</auth> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jU21NMFlHcHpSZUpVQlhGRW1VcGh5MlpTeVNmdXJmSFVid2tROHZwLHM9R3g5WDRKZHpMbnFHd0E9PSxpPTQwOTY=</challenge> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y1NtTTBZR3B6UmVKVUJYRkVtVXBoeTJaU3lTZnVyZkhVYndrUTh2cCxwPWpKd1piVTJBaXhsY0p1OHhveUszd0lscU5OMTJ6aUZDMDNpQThWRFhZTGs9</response> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1EVTBHd0l3QkYyTjdnMlpmNlp1VFNqcTlZclJWaVJ1L200TU9JMTdUUHpBPQ==</success> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c node="https://tigase.net/tigase-xmpp-server" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="U50wTE5DO0LdCVLF7H+/k5Q8gBA="/></features> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost >> <iq xmlns="jabber:client" id="d1e9i3gAs48zsZ2lLV4ys1g" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost << <iq id="d1e9i3gAs48zsZ2lLV4ys1g" xmlns="jabber:client" to="jaxmpp__hveepv0538@localhost/943755961-tigase-298" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__hveepv0538@localhost/943755961-tigase-298</jid></bind></iq> 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 >> <iq xmlns="jabber:client" id="NVUeKEiT6hCv5zm8ClUQV6e" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 << <iq id="NVUeKEiT6hCv5zm8ClUQV6e" xmlns="jabber:client" to="jaxmpp__hveepv0538@localhost/943755961-tigase-298" type="result"/> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 >> <iq to="localhost" id="G2dVZfPELGq8qQZiFo3bLKU" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 >> <iq id="v9wveudGohZhNQPNR36Fu2m" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream 2020-01-02 19:58:05 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected 2020-01-02 19:58:05 | jaxmpp_ :: jaxmpp__hveepv0538@localhost/943755961-tigase-298 << <enabled id="aeec8f68-76a6-4440-9e83-15119375a3e2" xmlns="urn:xmpp:sm:3" resume="true" location="2938d31d0913" max="60"/> 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-01-02 19:58:05 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-01-02 19:58:08 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |