Testing reconnections - #4266

2020-03-29 22:50:33 |

2020-03-29 22:50:33 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-03-29 22:50:33 | ------------------------------------

2020-03-29 22:50:33 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:33 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-03-29 22:50:33 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:34 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3h4eGY4MDU1MSxyPUgwVTJ6Y2ZVdGxoZFZpVFJGVXNC</auth>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IMFUyemNmVXRsaGRWaVRSRlVzQkxmM0t2eWZyR0RHMmxiVnR3OVhELHM9Nkt6RklSVm9GODF3SHc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SDBVMnpjZlV0bGhkVmlUUkZVc0JMZjNLdnlmckdERzJsYlZ0dzlYRCxwPW1POXRVTm9aVWNhSTRTVjg5WUp1OU9VRGE0dEJISmRRVXZrUHJjekZRTms9</response>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1XZ1ZWN1JnbEVhTjFmcHRqUHdEMGZMSkR2c3BGbG11R04vWkdFM0NRMkVJPQ==</success>

2020-03-29 22:50:34 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <iq xmlns="jabber:client" id="p0sITeueMPMYfWjPVayqX2z" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-302" type="result" id="p0sITeueMPMYfWjPVayqX2z"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__xxxf80551@localhost/2095755800-tigase-302</jid></bind></iq>

2020-03-29 22:50:34 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 >> <iq to="localhost" id="ZFpp8xcOVi1fLQU8nSfyu5x" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 >> <iq id="Y6GJIKLEIJUfHFEwmPM3aIx" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-302" type="result" id="Y6GJIKLEIJUfHFEwmPM3aIx"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-302" type="result" from="localhost" id="ZFpp8xcOVi1fLQU8nSfyu5x"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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-03-29 22:50:34 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-302 << <presence xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost" from="jaxmpp__xxxf80551@localhost/2095755800-tigase-302"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-03-29 22:50:36 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2020-03-29 22:50:36 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2020-03-29 22:50:36 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-03-29 22:50:36 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-03-29 22:50:38 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-03-29 22:50:38 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2020-03-29 22:50:38 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:38 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:38 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3h4eGY4MDU1MSxyPUFOM2ZqMXhuRVgyOTc4NjZhVEFS</auth>

2020-03-29 22:50:38 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1BTjNmajF4bkVYMjk3ODY2YVRBUm1OUElpS1ZNa1ZMZkJyZ3BhSTRWLHM9Nkt6RklSVm9GODF3SHc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:38 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QU4zZmoxeG5FWDI5Nzg2NmFUQVJtTlBJaUtWTWtWTGZCcmdwYUk0VixwPXU2ZDZ2TVAyZzRZcDhVeWNNeGZIR1Q3alJlQS9ST3ZhVzlTNVpzcGNUdU09</response>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1JTnJFVGZzQlRsdHRyclEwc1lRam9EMjdlL1IxTmlBdHorSlhnSjJXUzlVPQ==</success>

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost >> <iq xmlns="jabber:client" id="vZjeAAtodDxo0kLENEGtjRT" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-303" type="result" id="vZjeAAtodDxo0kLENEGtjRT"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__xxxf80551@localhost/2095755800-tigase-303</jid></bind></iq>

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-303 >> <iq to="localhost" id="6TZAucIxcTQ4fRX5DCEJDEL" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-303 >> <iq id="MOI8vbP0qhJQ9LYYcIHwsdS" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-303 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-303 << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-303" type="result" from="localhost" id="6TZAucIxcTQ4fRX5DCEJDEL"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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-03-29 22:50:39 | jaxmpp_ :: jaxmpp__xxxf80551@localhost/2095755800-tigase-303 << <iq xmlns="jabber:client" to="jaxmpp__xxxf80551@localhost/2095755800-tigase-303" type="result" id="MOI8vbP0qhJQ9LYYcIHwsdS"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2020-03-29 22:50:39 |

2020-03-29 22:50:39 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-03-29 22:50:39 | ------------------------------------

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Connector  state changed: null->connecting

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] DNS entry stored in session object: localhost:5222

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Preparing connection to [localhost:5222]

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Connector  state changed: connecting->connected

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Whitespace ping period is setted to nullms

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=3972] Start TLS

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=3974] Proceeding TLS

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=3974] Start handshake

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzhvODdwazA1NTIscj1HWEdnY0owT3cwSnpITlZzYU1DcA==</auth>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HWEdnY0owT3cwSnpITlZzYU1DcFBpTzRDeVc5UmpWUmVXOW1seGJPLHM9MVVFR1hmOXBEZytxUWc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9R1hHZ2NKME93MEp6SE5Wc2FNQ3BQaU80Q3lXOVJqVlJlVzltbHhiTyxwPXJJNm11c2FzNXZtWTVDMFNabTB2K3ZxUGhQYm43TnZOVHlqcjJydzJTQ0U9</response>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1kNWhmZWlkNENUTEhUUWRoRStobkduS1d1c3pESVdKeXpxVzlKNHBwZU8wPQ==</success>

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <iq xmlns="jabber:client" id="R7vLX5yzBEkU8xcOVi1fLQU" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-304" type="result" id="R7vLX5yzBEkU8xcOVi1fLQU"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__8o87pk0552@localhost/2095755800-tigase-304</jid></bind></iq>

2020-03-29 22:50:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 >> <iq xmlns="jabber:client" id="5dL4p8uiHwx8EpbBndMYnVe" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-304" type="result" id="5dL4p8uiHwx8EpbBndMYnVe"/>

2020-03-29 22:50:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 >> <iq to="localhost" id="w4i3wl8HBtgKOJAFoarfJDJ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 << <enabled location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3" resume="true" id="cc49eb23-1600-4e93-aa62-c9d172cb65d6"/>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 >> <iq id="IWSAeQNX9tpaKV1qj5CgMS1" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-304" type="result" from="localhost" id="w4i3wl8HBtgKOJAFoarfJDJ"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-304" type="result" id="IWSAeQNX9tpaKV1qj5CgMS1"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:39 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-304 << <presence xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost" from="jaxmpp__8o87pk0552@localhost/2095755800-tigase-304"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-03-29 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Connector  state changed: connected->disconnecting

2020-03-29 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=1] Terminating XMPP Stream

2020-03-29 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:41 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=621 thread=3974] Connector  state changed: disconnecting->disconnected

2020-03-29 22:50:41 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-03-29 22:50:41 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] Connector  state changed: null->connecting

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] DNS entry stored in session object: localhost:5222

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] Preparing connection to [localhost:5222]

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] Connector  state changed: connecting->connected

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=1] Whitespace ping period is setted to nullms

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=3972] Start TLS

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=3979] Proceeding TLS

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=3979] Start handshake

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzhvODdwazA1NTIscj1tN0l5elg0Y2JCblZvOHVmaEFWQQ==</auth>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1tN0l5elg0Y2JCblZvOHVmaEFWQXVUS25hRUlDeTMzS3NwMzhqdmxXLHM9MVVFR1hmOXBEZytxUWc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bTdJeXpYNGNiQm5Wbzh1ZmhBVkF1VEtuYUVJQ3kzM0tzcDM4anZsVyxwPTBxTVMxbDd2eGs5cnJFWGNBZFpKcEVtVXN1Sk5oeExZWWtWZ1g0MDVZZ0U9</response>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj14TGR0cGdKSmN5dkJtcnJxTUs5U1FnbnBaTXRCTklaVitSTGRicWkxeGJRPQ==</success>

2020-03-29 22:50:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost >> <iq xmlns="jabber:client" id="LdFMV5l9EIRTaN3y8wmZ6Cx" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-305" type="result" id="LdFMV5l9EIRTaN3y8wmZ6Cx"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__8o87pk0552@localhost/2095755800-tigase-305</jid></bind></iq>

2020-03-29 22:50:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 >> <iq xmlns="jabber:client" id="XjVDiQ0hGMRaLJJMMSRQbqe" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-03-29 22:50:43 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-305" type="result" id="XjVDiQ0hGMRaLJJMMSRQbqe"/>

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 >> <iq to="localhost" id="AjTyjT6qar0v4w9Fzj5wslR" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 >> <iq id="AkUAn1mWcv8LAydNPP7AABn" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 << <enabled location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3" resume="true" id="c9ff6d20-dc23-4493-aea7-5da9c78dad16"/>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-305" type="result" from="localhost" id="AjTyjT6qar0v4w9Fzj5wslR"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 << <iq xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost/2095755800-tigase-305" type="result" id="AkUAn1mWcv8LAydNPP7AABn"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8o87pk0552@localhost/2095755800-tigase-305 << <presence xmlns="jabber:client" to="jaxmpp__8o87pk0552@localhost" from="jaxmpp__8o87pk0552@localhost/2095755800-tigase-305"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=3979] Connector  state changed: connected->disconnected

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=622 thread=3979] Stream terminated

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:44 |

2020-03-29 22:50:44 | null / [TestClass name=class tigase.tests.jaxmpp.TestReconnections]

2020-03-29 22:50:44 | ------------------------------------

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzh6dnFwMTA1NTMscj1rcTFveWRORHFFQndMdUZzNVFXdg==</auth>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1rcTFveWRORHFFQndMdUZzNVFXdlYxbGlBVlRod3hnajduVENWd290LHM9cTFoQm9kRUxrWE9TSHc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9a3Exb3lkTkRxRUJ3THVGczVRV3ZWMWxpQVZUaHd4Z2o3blRDVndvdCxwPStJQ3oxdXZuWDNhYms5STkrV0orVGkrQTFWWURxS3lCOHBJaUdkcTZWRHc9</response>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0wb3dnMFBLY0d4SzhCa08zSjdHNW5lOW1leS9lTGtpZkh0MDdaTC95SEU0PQ==</success>

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <iq xmlns="jabber:client" id="AoYIDxo0kLENEGtjRTfQ6Dr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-306" type="result" id="AoYIDxo0kLENEGtjRTfQ6Dr"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__8zvqp10553@localhost/2095755800-tigase-306</jid></bind></iq>

2020-03-29 22:50:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 >> <iq xmlns="jabber:client" id="Wq2Scq6wjKU7GNNR2iUVYrW" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-306" type="result" id="Wq2Scq6wjKU7GNNR2iUVYrW"/>

2020-03-29 22:50:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 >> <iq to="localhost" id="H5ZrnO07Br3zuodBADJUQWc" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 >> <iq id="CcicIEClO7iY3tbxxqgX5yz" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 << <enabled location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3" resume="true" id="bde8d1f1-4d2b-4fd4-9259-47cc6632c4a7"/>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-306" type="result" id="CcicIEClO7iY3tbxxqgX5yz"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-306" type="result" from="localhost" id="H5ZrnO07Br3zuodBADJUQWc"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority: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-03-29 22:50:44 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-306 << <presence xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost" from="jaxmpp__8zvqp10553@localhost/2095755800-tigase-306"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-03-29 22:50:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2020-03-29 22:50:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-03-29 22:50:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2020-03-29 22:50:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-03-29 22:50:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2020-03-29 22:50:48 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:48 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-03-29 22:50:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-03-29 22:50:48 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzh6dnFwMTA1NTMscj1BZGtmUlNYUlpjalVBcGJpQWFIZA==</auth>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1BZGtmUlNYUlpjalVBcGJpQWFIZGVmSGJ3QTJTN2J2OUJqUm5rU0RjLHM9cTFoQm9kRUxrWE9TSHc9PSxpPTQwOTY=</challenge>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QWRrZlJTWFJaY2pVQXBiaUFhSGRlZkhid0EyUzdidjlCalJua1NEYyxwPWFqU1d6N3pBOXJ5TkZ6dzlBWmlTdUl3VDVHZis1SGVKQWpKaFE0R291eG89</response>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1zbzEyYVBHWjF6dEYzcWlTMlI3TDJDaUFqaWhxeEFnc2k5WElBb3c1QU5FPQ==</success>

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@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 xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost >> <iq xmlns="jabber:client" id="H71vv4w9Fzj5wslR6FN26se" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-307" type="result" id="H71vv4w9Fzj5wslR6FN26se"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__8zvqp10553@localhost/2095755800-tigase-307</jid></bind></iq>

2020-03-29 22:50:49 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 >> <iq xmlns="jabber:client" id="GlGNIVUcvnZ9LSaLHM1vsW8" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 << <iq xmlns="jabber:client" to="jaxmpp__8zvqp10553@localhost/2095755800-tigase-307" type="result" id="GlGNIVUcvnZ9LSaLHM1vsW8"/>

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 >> <iq to="localhost" id="Oitrl2upZmbP6eIKLEIJUfH" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 >> <iq id="VsVDJUQWcFskXm9vhSYgDoh" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-03-29 22:50:49 | jaxmpp_ :: jaxmpp__8zvqp10553@localhost/2095755800-tigase-307 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2020-03-29 22:50:49 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-03-29 22:50:49 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected