Generated by TestNG with ReportNG at 22:20 PDT on Thursday 08 September 2022
root@ed1b7dd8a1b3 / Java 17.0.2 (Private Build) / Linux 4.14.281-212.502.amzn2.x86_64 (amd64)

Testing state of JaXMPP instance after issues with connection or authentication timeout - #4378

Suites · Log Output

Test duration : 64.694s

Passed Tests
tigase.tests.jaxmpp.TestHandlingOfConnectionIssues
testStateAfterAuthTimeoutBosh 20.613s
2022-09-08 22:43:42 |

2022-09-08 22:43:42 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:43:42 | ------------------------------------

2022-09-08 22:43:42 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:43:42 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:43:42 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2022-09-08 22:43:42 |  >> <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-512</mechanism><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>

2022-09-08 22:43:42 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:02 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2022-09-08 22:44:02 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:02 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:02 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2022-09-08 22:44:03 |  >> <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-512</mechanism><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>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:03 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3B0aTU0YjA1NTkscj15bzJMVmxUTVlodlV4QkZFRHJCVA==</auth>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3B0aTU0YjA1NTkscj15bzJMVmxUTVlodlV4QkZFRHJCVA==</auth>

2022-09-08 22:44:03 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15bzJMVmxUTVlodlV4QkZFRHJCVDRIOHE2czZXVkRQczJLWkw3ZnY0LHM9SnRSWUM5dmNuZ0dnN3c9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15bzJMVmxUTVlodlV4QkZFRHJCVDRIOHE2czZXVkRQczJLWkw3ZnY0LHM9SnRSWUM5dmNuZ0dnN3c9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:03 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eW8yTFZsVE1ZaHZVeEJGRURyQlQ0SDhxNnM2V1ZEUHMyS1pMN2Z2NCxwPVROc0M2VmtmMEZ1ZjJaSXBWOFVENUhlUG81eE5mRktRYkxvQTJ0R01Udnc9</response>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eW8yTFZsVE1ZaHZVeEJGRURyQlQ0SDhxNnM2V1ZEUHMyS1pMN2Z2NCxwPVROc0M2VmtmMEZ1ZjJaSXBWOFVENUhlUG81eE5mRktRYkxvQTJ0R01Udnc9</response>

2022-09-08 22:44:03 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0zb1FTOVlOOEI3aGVmdGgzMmh5WnpPcHpSdVdJSjNlK1JudTNza00xTDFRPQ==</success>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0zb1FTOVlOOEI3aGVmdGgzMmh5WnpPcHpSdVdJSjNlK1JudTNza00xTDFRPQ==</success>

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:03 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:03 |  << <iq xmlns="jabber:client" id="3QV8FDFJLzamcFOFFtaso3d" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com >> <iq xmlns="jabber:client" id="3QV8FDFJLzamcFOFFtaso3d" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 |  << <iq xmlns="jabber:client" id="s3wvbRQHwdqfNZn39kGQXoP" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com >> <iq xmlns="jabber:client" id="s3wvbRQHwdqfNZn39kGQXoP" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 |  << <iq xmlns="jabber:client" id="KYfgzt5oUiRP4zBGJDrnSLE" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com >> <iq xmlns="jabber:client" id="KYfgzt5oUiRP4zBGJDrnSLE" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:03 |  >> <iq id="3QV8FDFJLzamcFOFFtaso3d" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326</jid></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com << <iq id="3QV8FDFJLzamcFOFFtaso3d" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326</jid></bind></iq>

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:03 |  << <iq to="test-domain.com" id="LcT7zhMGQX1sl9MV1dVYX2f" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326 >> <iq to="test-domain.com" id="LcT7zhMGQX1sl9MV1dVYX2f" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 |  << <iq id="uUZLEjKFk3pdPXXWUiRcrdz" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326 >> <iq id="uUZLEjKFk3pdPXXWUiRcrdz" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 |  >> <iq id="s3wvbRQHwdqfNZn39kGQXoP" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327</jid></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-326 << <iq id="s3wvbRQHwdqfNZn39kGQXoP" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327</jid></bind></iq>

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:03 |  << <iq to="test-domain.com" id="kSRufBCFN4vbxzgxcz8FDFJ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327 >> <iq to="test-domain.com" id="kSRufBCFN4vbxzgxcz8FDFJ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 |  >> <iq id="KYfgzt5oUiRP4zBGJDrnSLE" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328</jid></bind></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-327 << <iq id="KYfgzt5oUiRP4zBGJDrnSLE" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328</jid></bind></iq>

2022-09-08 22:44:03 |  << <iq id="7aH5tZ02ynevlPSSeJzyxxy" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 >> <iq id="7aH5tZ02ynevlPSSeJzyxxy" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:03 |  << <iq to="test-domain.com" id="uX2RQHwdqfNZn39kGQXoPZ7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 >> <iq to="test-domain.com" id="uX2RQHwdqfNZn39kGQXoPZ7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:03 |  << <iq id="m4l5ABFOZ9tr01av0p7DFDj" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 >> <iq id="m4l5ABFOZ9tr01av0p7DFDj" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 |  >> <iq id="uUZLEjKFk3pdPXXWUiRcrdz" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 << <iq id="uUZLEjKFk3pdPXXWUiRcrdz" to="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:03 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:03 |  >> <presence from="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" to="jaxmpp__pti54b0559@test-domain.com" xmlns="jabber:client"><c hash="sha-1" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328 << <presence from="jaxmpp__pti54b0559@test-domain.com/1608752850-tigase-328" to="jaxmpp__pti54b0559@test-domain.com" xmlns="jabber:client"><c hash="sha-1" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500
testStateAfterAuthTimeoutSocket 20.324s
2022-09-08 22:44:03 |

2022-09-08 22:44:03 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:44:03 | ------------------------------------

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] Connector  state changed: null->connecting

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] DNS entry stored in session object: localhost:5222

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] Preparing connection to [localhost:5222]

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] Opening connection to localhost/127.0.0.1:5222

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] Connector  state changed: connecting->connected

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=1] Whitespace ping period is setted to nullms

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:03 |  >> <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>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <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>

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=4099] Start TLS

2022-09-08 22:44:03 |  << <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=4101] Proceeding TLS

2022-09-08 22:44:03 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=4101] Start handshake

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:03 |  >> <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-512</mechanism><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>

2022-09-08 22:44:03 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:03 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=4101] Connector  state changed: connected->disconnected

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=633 thread=4101] Stream terminated

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Connector  state changed: null->connecting

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] DNS entry stored in session object: localhost:5222

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Preparing connection to [localhost:5222]

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Opening connection to localhost/127.0.0.1:5222

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Connector  state changed: connecting->connected

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Whitespace ping period is setted to nullms

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:23 |  >> <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>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <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>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=4099] Start TLS

2022-09-08 22:44:23 |  << <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=4106] Proceeding TLS

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=4106] Start handshake

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:23 |  >> <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-512</mechanism><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>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:23 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3pjbmt4MDU2MCxyPVlHWWxJTk9lUDZSY29pcmN5eEc5</auth>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX3pjbmt4MDU2MCxyPVlHWWxJTk9lUDZSY29pcmN5eEc5</auth>

2022-09-08 22:44:23 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ZR1lsSU5PZVA2UmNvaXJjeXhHOWNKd0ZXUUJhd0t5ZmFHR1FQZ2IyLHM9SWd4ZDRlNnc5d1AyclE9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ZR1lsSU5PZVA2UmNvaXJjeXhHOWNKd0ZXUUJhd0t5ZmFHR1FQZ2IyLHM9SWd4ZDRlNnc5d1AyclE9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:23 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WUdZbElOT2VQNlJjb2lyY3l4RzljSndGV1FCYXdLeWZhR0dRUGdiMixwPXJJL21oSlYwS3loYUx5Nmg2ZmMwOFZva2NrS0FaMzdBL2N1NlEyMDF5UnM9</response>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WUdZbElOT2VQNlJjb2lyY3l4RzljSndGV1FCYXdLeWZhR0dRUGdiMixwPXJJL21oSlYwS3loYUx5Nmg2ZmMwOFZva2NrS0FaMzdBL2N1NlEyMDF5UnM9</response>

2022-09-08 22:44:23 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1NeTF6c1RHZXhydGd4T0FFK0I2YW1tYTNZOSsyQVViV25rWkR6RkExNS84PQ==</success>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1NeTF6c1RHZXhydGd4T0FFK0I2YW1tYTNZOSsyQVViV25rWkR6RkExNS84PQ==</success>

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:23 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="S8pdQ7HS7pZt49q12tfTbFn" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <iq xmlns="jabber:client" id="S8pdQ7HS7pZt49q12tfTbFn" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="z38yvj1rbpQIU5El1bAi4pV" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <iq xmlns="jabber:client" id="z38yvj1rbpQIU5El1bAi4pV" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 |  >> <iq id="S8pdQ7HS7pZt49q12tfTbFn" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-329" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-329</jid></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <iq id="S8pdQ7HS7pZt49q12tfTbFn" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-329" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-329</jid></bind></iq>

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="349AzrhXdtYYq7IthHCmcFr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com >> <iq xmlns="jabber:client" id="349AzrhXdtYYq7IthHCmcFr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:23 |  >> <iq id="z38yvj1rbpQIU5El1bAi4pV" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330</jid></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <iq id="z38yvj1rbpQIU5El1bAi4pV" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330</jid></bind></iq>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="i34EqkVXk5DKRTXpWTUZYlZ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330 >> <iq xmlns="jabber:client" id="i34EqkVXk5DKRTXpWTUZYlZ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 |  >> <iq id="349AzrhXdtYYq7IthHCmcFr" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331</jid></bind></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-330 << <iq id="349AzrhXdtYYq7IthHCmcFr" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331</jid></bind></iq>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="O45Gusbtm9L0nV1xcpW36Bv" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq xmlns="jabber:client" id="O45Gusbtm9L0nV1xcpW36Bv" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 |  >> <iq id="i34EqkVXk5DKRTXpWTUZYlZ" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq id="i34EqkVXk5DKRTXpWTUZYlZ" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:23 |  << <iq xmlns="jabber:client" id="BlSl5zrVSM2lan5jQO6t3wt" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq xmlns="jabber:client" id="BlSl5zrVSM2lan5jQO6t3wt" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:23 |  >> <iq id="O45Gusbtm9L0nV1xcpW36Bv" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq id="O45Gusbtm9L0nV1xcpW36Bv" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 |  << <iq to="test-domain.com" id="Wq5WfFvv8hGx4xn9HHrUQaB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq to="test-domain.com" id="Wq5WfFvv8hGx4xn9HHrUQaB" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:23 |  >> <enabled resume="true" id="c10c0889-63fc-4fe9-b990-66cdcf86bf1e" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <enabled resume="true" id="c10c0889-63fc-4fe9-b990-66cdcf86bf1e" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:23 |  << <iq id="pp0L0nV1xcpW36BvpdJv26G" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq id="pp0L0nV1xcpW36BvpdJv26G" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 |  >> <iq id="BlSl5zrVSM2lan5jQO6t3wt" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq id="BlSl5zrVSM2lan5jQO6t3wt" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:23 |  << <iq to="test-domain.com" id="KgxQJNJGusbtm9L0nV1xcpW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq to="test-domain.com" id="KgxQJNJGusbtm9L0nV1xcpW" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 |  << <iq to="test-domain.com" id="i9aQO6t3wtpiX5lbuZ6nKT5" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq to="test-domain.com" id="i9aQO6t3wtpiX5lbuZ6nKT5" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:23 |  << <iq id="qua5xfFPMzgHobFJR15cqkV" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq id="qua5xfFPMzgHobFJR15cqkV" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 |  >> <iq from="test-domain.com" id="Wq5WfFvv8hGx4xn9HHrUQaB" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq from="test-domain.com" id="Wq5WfFvv8hGx4xn9HHrUQaB" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:23 |  << <iq id="KjBXXfDu6GDnaLZshJDLEjK" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <iq id="KjBXXfDu6GDnaLZshJDLEjK" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 |  >> <iq id="pp0L0nV1xcpW36BvpdJv26G" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq id="pp0L0nV1xcpW36BvpdJv26G" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:23 |  >> <iq from="test-domain.com" id="KgxQJNJGusbtm9L0nV1xcpW" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331 << <iq from="test-domain.com" id="KgxQJNJGusbtm9L0nV1xcpW" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Connector  state changed: connected->disconnecting

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=1] Terminating XMPP Stream

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:23 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:23 |  >> <iq id="qua5xfFPMzgHobFJR15cqkV" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | jaxmpp_ :: jaxmpp__zcnkx0560@test-domain.com << <iq id="qua5xfFPMzgHobFJR15cqkV" to="jaxmpp__zcnkx0560@test-domain.com/1608752850-tigase-331" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:23 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=634 thread=4106] Connector  state changed: disconnecting->disconnected
testStateAfterAuthTimeoutWebSocket 20.392s
2022-09-08 22:44:24 |

2022-09-08 22:44:24 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:44:24 | ------------------------------------

2022-09-08 22:44:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2022-09-08 22:44:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2022-09-08 22:44:24 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2022-09-08 22:44:24 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2022-09-08 22:44:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:24 |  >> <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-512</mechanism><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>

2022-09-08 22:44:24 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.onStreamTerminate: Stream terminated

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:44 |  >> <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-512</mechanism><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>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:44 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRnaG52dTA1NjEscj1zUmhwTzQwRjZiYklrVVgzcWRSWA==</auth>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzRnaG52dTA1NjEscj1zUmhwTzQwRjZiYklrVVgzcWRSWA==</auth>

2022-09-08 22:44:44 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1zUmhwTzQwRjZiYklrVVgzcWRSWDNZNmdHY3UybVJUa2NBVHB6WjR6LHM9eUoranZQZlczcmdxVXc9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1zUmhwTzQwRjZiYklrVVgzcWRSWDNZNmdHY3UybVJUa2NBVHB6WjR6LHM9eUoranZQZlczcmdxVXc9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:44 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9c1JocE80MEY2YmJJa1VYM3FkUlgzWTZnR2N1Mm1SVGtjQVRwelo0eixwPTUweDVKK0t5dStmN3NmSWR5S1UxTkpEVVNsNkJsQVM1ck94ZW9hSjVMZDQ9</response>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9c1JocE80MEY2YmJJa1VYM3FkUlgzWTZnR2N1Mm1SVGtjQVRwelo0eixwPTUweDVKK0t5dStmN3NmSWR5S1UxTkpEVVNsNkJsQVM1ck94ZW9hSjVMZDQ9</response>

2022-09-08 22:44:44 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1HUlRoUTlJOVE5STBJdk9CODd0WjNYQ1dQamxoVnAwU1Ntek1GL3BmZXF3PQ==</success>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1HUlRoUTlJOVE5STBJdk9CODd0WjNYQ1dQamxoVnAwU1Ntek1GL3BmZXF3PQ==</success>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:44 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="c8WDxy9Dp2cS19IRbQ3hJX2" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com >> <iq xmlns="jabber:client" id="c8WDxy9Dp2cS19IRbQ3hJX2" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="YuYoXpWTUZYlZn9Bfw4Cvu1" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com >> <iq xmlns="jabber:client" id="YuYoXpWTUZYlZn9Bfw4Cvu1" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="7x5QZ14aOTgzt5oUiRP4zBG" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com >> <iq xmlns="jabber:client" id="7x5QZ14aOTgzt5oUiRP4zBG" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:44 |  >> <iq id="c8WDxy9Dp2cS19IRbQ3hJX2" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-332" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-332</jid></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <iq id="c8WDxy9Dp2cS19IRbQ3hJX2" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-332" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-332</jid></bind></iq>

2022-09-08 22:44:44 |  >> <iq id="YuYoXpWTUZYlZn9Bfw4Cvu1" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-333" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-333</jid></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <iq id="YuYoXpWTUZYlZn9Bfw4Cvu1" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-333" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-333</jid></bind></iq>

2022-09-08 22:44:44 |  >> <iq id="7x5QZ14aOTgzt5oUiRP4zBG" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334</jid></bind></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com << <iq id="7x5QZ14aOTgzt5oUiRP4zBG" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334</jid></bind></iq>

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="Ey6MBeusbQJNJGusbtm9L0n" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq xmlns="jabber:client" id="Ey6MBeusbQJNJGusbtm9L0n" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="5z7O1rbpQIU5El1bAi4pVgX" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq xmlns="jabber:client" id="5z7O1rbpQIU5El1bAi4pVgX" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 |  << <iq xmlns="jabber:client" id="0jjUW36BvpdJv26GDKxpX8o" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq xmlns="jabber:client" id="0jjUW36BvpdJv26GDKxpX8o" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:44 |  >> <iq id="Ey6MBeusbQJNJGusbtm9L0n" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 << <iq id="Ey6MBeusbQJNJGusbtm9L0n" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 |  >> <iq id="5z7O1rbpQIU5El1bAi4pVgX" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 << <iq id="5z7O1rbpQIU5El1bAi4pVgX" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 |  >> <iq id="0jjUW36BvpdJv26GDKxpX8o" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 << <iq id="0jjUW36BvpdJv26GDKxpX8o" to="jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:44 |  << <iq to="test-domain.com" id="Qkkp5hYndUbxfZ6dyhyvj1r" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq to="test-domain.com" id="Qkkp5hYndUbxfZ6dyhyvj1r" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 |  << <iq to="test-domain.com" id="oDd8JWfFvv8hGx4xn9HHrUQ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq to="test-domain.com" id="oDd8JWfFvv8hGx4xn9HHrUQ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 |  << <iq to="test-domain.com" id="1f1r36BvpdJv26GDKxpX8og" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq to="test-domain.com" id="1f1r36BvpdJv26GDKxpX8og" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:44 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:44 |  << <iq id="ynncobFJR15cqkVkHJBqYq7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq id="ynncobFJR15cqkVkHJBqYq7" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 |  << <iq id="JKssoPZ7s2ynR8HRcLHzDoN" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq id="JKssoPZ7s2ynR8HRcLHzDoN" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 |  << <iq id="FAWcqSeJWVbveAEzfUfXo9t" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <iq id="FAWcqSeJWVbveAEzfUfXo9t" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:44 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:44 |  >> <enabled resume="true" id="70c441d2-91ba-4fc9-893e-6fed0d9a0bcf" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:44 | jaxmpp_ :: jaxmpp__4ghnvu0561@test-domain.com/1608752850-tigase-334 << <enabled resume="true" id="70c441d2-91ba-4fc9-893e-6fed0d9a0bcf" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2022-09-08 22:44:47 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testStateAfterConnectionFailureBosh 0.749s
2022-09-08 22:44:44 |

2022-09-08 22:44:44 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:44:44 | ------------------------------------

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.connectors.bosh.BoshWorker.run: Connection error

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=0

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2022-09-08 22:44:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using BOSHConnector

2022-09-08 22:44:45 |  >> <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-512</mechanism><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>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:45 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzU0b2xvdTA1NjIscj16cE5TT1ZxSG5GWUpKTzVhUUtqSQ==</auth>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzU0b2xvdTA1NjIscj16cE5TT1ZxSG5GWUpKTzVhUUtqSQ==</auth>

2022-09-08 22:44:45 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj16cE5TT1ZxSG5GWUpKTzVhUUtqSXlDaUx4dEh3REJNQ0dhUTdFejRCLHM9Q3NFWVYvKzdrTUwrd2c9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj16cE5TT1ZxSG5GWUpKTzVhUUtqSXlDaUx4dEh3REJNQ0dhUTdFejRCLHM9Q3NFWVYvKzdrTUwrd2c9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:45 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9enBOU09WcUhuRllKSk81YVFLakl5Q2lMeHRId0RCTUNHYVE3RXo0QixwPWxHekhEcXRsOW9OVjI5czhiQkF3SXVXbWF1K2loZCtwMjNDU2MzckwyT2c9</response>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9enBOU09WcUhuRllKSk81YVFLakl5Q2lMeHRId0RCTUNHYVE3RXo0QixwPWxHekhEcXRsOW9OVjI5czhiQkF3SXVXbWF1K2loZCtwMjNDU2MzckwyT2c9</response>

2022-09-08 22:44:45 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1kRHgrdUZUSlUwYjdURUhubytrYVJDMUJoMlAxY1NUN2pESFptNjNFU3pVPQ==</success>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1kRHgrdUZUSlUwYjdURUhubytrYVJDMUJoMlAxY1NUN2pESFptNjNFU3pVPQ==</success>

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:45 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:45 |  << <iq xmlns="jabber:client" id="jzLVkVXk5DKRTXpWTUZYlZn" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com >> <iq xmlns="jabber:client" id="jzLVkVXk5DKRTXpWTUZYlZn" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:45 |  >> <iq id="jzLVkVXk5DKRTXpWTUZYlZn" to="jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335</jid></bind></iq>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com << <iq id="jzLVkVXk5DKRTXpWTUZYlZn" to="jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335</jid></bind></iq>

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:45 |  << <iq to="test-domain.com" id="BLjcNZhBeusbQJNJGusbtm9" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335 >> <iq to="test-domain.com" id="BLjcNZhBeusbQJNJGusbtm9" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:45 |  << <iq id="iwyBk8xbMZrm5lRdy7mTMXd" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335 >> <iq id="iwyBk8xbMZrm5lRdy7mTMXd" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:45 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__54olou0562@test-domain.com/1608752850-tigase-335 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onTerminate: Stream terminated. responseCode=200

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.connector.AbstractBoshConnector.onError: responseCode=500

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]
testStateAfterConnectionFailureSocket 0.212s
2022-09-08 22:44:45 |

2022-09-08 22:44:45 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:44:45 | ------------------------------------

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=638 thread=1] Connector  state changed: null->connecting

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=638 thread=1] DNS entry stored in session object: missing:5222

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=638 thread=1] Preparing connection to [missing:5222]

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=638 thread=1] missing. Trying next.

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=638 thread=1] Connector  state changed: connecting->disconnected

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Connector  state changed: null->connecting

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] DNS entry stored in session object: localhost:5222

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Preparing connection to [localhost:5222]

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Opening connection to localhost/127.0.0.1:5222

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Connector  state changed: connecting->connected

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Whitespace ping period is setted to nullms

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:45 |  >> <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>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <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>

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=4160] Start TLS

2022-09-08 22:44:45 |  << <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=4164] Proceeding TLS

2022-09-08 22:44:45 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=4164] Start handshake

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:45 |  >> <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-512</mechanism><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>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:45 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2ptdDlmdTA1NjMscj00dGhXUGptQUhKZ2tvUWp3SU8wTw==</auth>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfX2ptdDlmdTA1NjMscj00dGhXUGptQUhKZ2tvUWp3SU8wTw==</auth>

2022-09-08 22:44:45 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj00dGhXUGptQUhKZ2tvUWp3SU8wT3V4RXFvbEY3Z0Y1UlpHQ2hUWThnLHM9WGl4QVpwV05NL3B3ckE9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj00dGhXUGptQUhKZ2tvUWp3SU8wT3V4RXFvbEY3Z0Y1UlpHQ2hUWThnLHM9WGl4QVpwV05NL3B3ckE9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:45 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NHRoV1BqbUFISmdrb1Fqd0lPME91eEVxb2xGN2dGNVJaR0NoVFk4ZyxwPTZYcVJuUlQ2eVBKL1BwdXJ6NkUrK3B0SVZBQ1I4ZGdzaUk2NWllMmpnYms9</response>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NHRoV1BqbUFISmdrb1Fqd0lPME91eEVxb2xGN2dGNVJaR0NoVFk4ZyxwPTZYcVJuUlQ2eVBKL1BwdXJ6NkUrK3B0SVZBQ1I4ZGdzaUk2NWllMmpnYms9</response>

2022-09-08 22:44:45 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13VHhnQklSZlN2NnhYbnVKNStrVVNBZ2ZJV1ZsWmk5Q1p3WHlZdkxJMU5JPQ==</success>

2022-09-08 22:44:45 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13VHhnQklSZlN2NnhYbnVKNStrVVNBZ2ZJV1ZsWmk5Q1p3WHlZdkxJMU5JPQ==</success>

2022-09-08 22:44:45 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:46 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:46 |  << <iq xmlns="jabber:client" id="uCIcw3eDgV9HNTPGogN4bHx" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com >> <iq xmlns="jabber:client" id="uCIcw3eDgV9HNTPGogN4bHx" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:46 |  >> <iq id="uCIcw3eDgV9HNTPGogN4bHx" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336</jid></bind></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com << <iq id="uCIcw3eDgV9HNTPGogN4bHx" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336</jid></bind></iq>

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:46 |  << <iq xmlns="jabber:client" id="5RpobLPHqSeJWVbveAEzfUf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 >> <iq xmlns="jabber:client" id="5RpobLPHqSeJWVbveAEzfUf" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:46 |  >> <iq id="5RpobLPHqSeJWVbveAEzfUf" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <iq id="5RpobLPHqSeJWVbveAEzfUf" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:46 |  << <iq to="test-domain.com" id="mK2sk5DKRTXpWTUZYlZn9Bf" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 >> <iq to="test-domain.com" id="mK2sk5DKRTXpWTUZYlZn9Bf" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:46 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:46 |  << <iq id="yCCGm7xtlZ14aOTgzt5oUiR" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 >> <iq id="yCCGm7xtlZ14aOTgzt5oUiR" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 |  >> <enabled resume="true" id="c9ca8a58-c0c0-4315-8991-611e31b69907" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <enabled resume="true" id="c9ca8a58-c0c0-4315-8991-611e31b69907" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:46 |  >> <iq from="test-domain.com" id="mK2sk5DKRTXpWTUZYlZn9Bf" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <iq from="test-domain.com" id="mK2sk5DKRTXpWTUZYlZn9Bf" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="router" category="component"/><identity name="Tigase ver. 8.3.0-SNAPSHOT-b12085/68bd9eb2" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="list-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mam:2#extended"/><feature var="urn:xmpp:mix:pam:2#archive"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:xmpp:extdisco:2"/><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>

2022-09-08 22:44:46 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:46 |  >> <iq id="yCCGm7xtlZ14aOTgzt5oUiR" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <iq id="yCCGm7xtlZ14aOTgzt5oUiR" to="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" type="result" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Connector  state changed: connected->disconnecting

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=1] Terminating XMPP Stream

2022-09-08 22:44:46 |  >> <presence from="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" to="jaxmpp__jmt9fu0563@test-domain.com" xmlns="jabber:client"><c hash="sha-1" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <presence from="jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336" to="jaxmpp__jmt9fu0563@test-domain.com" xmlns="jabber:client"><c hash="sha-1" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2022-09-08 22:44:46 |  >> <message from="test-domain.com" to="jaxmpp__jmt9fu0563@test-domain.com" xmlns="jabber:client"><body>Wecome to 9a7ae3a0-e7ef-4b91-a43f-5a9277162b0e
This message was set at: Thu Sep 08 22:20:53 PDT 2022</body><delay from="test-domain.com" stamp="2022-09-09T05:44:45.803Z" xmlns="urn:xmpp:delay">Offline Storage - ed1b7dd8a1b3</delay></message>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__jmt9fu0563@test-domain.com/1608752850-tigase-336 << <message from="test-domain.com" to="jaxmpp__jmt9fu0563@test-domain.com" xmlns="jabber:client"><body>Wecome to 9a7ae3a0-e7ef-4b91-a43f-5a9277162b0e
This message was set at: Thu Sep 08 22:20:53 PDT 2022</body><delay from="test-domain.com" stamp="2022-09-09T05:44:45.803Z" xmlns="urn:xmpp:delay">Offline Storage - ed1b7dd8a1b3</delay></message>

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=4164] Connector  state changed: disconnecting->disconnected

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=639 thread=4164] Stream terminated

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testStateAfterConnectionFailureWebSocket 0.365s
2022-09-08 22:44:46 |

2022-09-08 22:44:46 | null / [TestClass name=class tigase.tests.jaxmpp.TestHandlingOfConnectionIssues]

2022-09-08 22:44:46 | ------------------------------------

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->disconnected

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream, session]

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using WebSocketConnector

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Start connector.

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: null->connecting

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Preparing connection to localhost

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Opening connection to localhost/127.0.0.1:5290

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connecting->connected

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.websocket.WebSocketConnector.start: Whitespace ping period is setted to nullms

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:46 |  >> <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-512</mechanism><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>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com << <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-512</mechanism><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>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-09-08 22:44:46 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFsb2I2MzA1NjQscj1ad29SU2pFcmwwNjJJdG9qVzg4MQ==</auth>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1qYXhtcHBfXzFsb2I2MzA1NjQscj1ad29SU2pFcmwwNjJJdG9qVzg4MQ==</auth>

2022-09-08 22:44:46 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ad29SU2pFcmwwNjJJdG9qVzg4MVgxTUozMDZtdmRFTzRpT01FRUszLHM9WUpsK0tKTHNjN3JOZnc9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1ad29SU2pFcmwwNjJJdG9qVzg4MVgxTUozMDZtdmRFTzRpT01FRUszLHM9WUpsK0tKTHNjN3JOZnc9PSxpPTQwOTY=</challenge>

2022-09-08 22:44:46 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WndvUlNqRXJsMDYySXRvalc4ODFYMU1KMzA2bXZkRU80aU9NRUVLMyxwPTBOV3FFYVZtL2xoQkozZVpUSkFRWG1zNEZScW11SGhaQTdUNkhOSTc0VEE9</response>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WndvUlNqRXJsMDYySXRvalc4ODFYMU1KMzA2bXZkRU80aU9NRUVLMyxwPTBOV3FFYVZtL2xoQkozZVpUSkFRWG1zNEZScW11SGhaQTdUNkhOSTc0VEE9</response>

2022-09-08 22:44:46 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1PSmNvRnF5bWdmYjhrMGFSQjhyc2NBZjl2c0ZnaTZZaEJYVy8xZGxKaTY4PQ==</success>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1PSmNvRnF5bWdmYjhrMGFSQjhyc2NBZjl2c0ZnaTZZaEJYVy8xZGxKaTY4PQ==</success>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-09-08 22:44:46 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" ver="97hw7wxADXqxSeJzTp9CBz5pjmg=" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps"/></features>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-09-08 22:44:46 |  << <iq xmlns="jabber:client" id="sVpgHTUPMJsjVfT5dsWmZsX" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com >> <iq xmlns="jabber:client" id="sVpgHTUPMJsjVfT5dsWmZsX" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-09-08 22:44:46 |  >> <iq id="sVpgHTUPMJsjVfT5dsWmZsX" to="jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337</jid></bind></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com << <iq id="sVpgHTUPMJsjVfT5dsWmZsX" to="jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337" type="result" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337</jid></bind></iq>

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-09-08 22:44:46 |  << <iq xmlns="jabber:client" id="cBpzpiDBlUWmZ5ABFOZ9tr0" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 >> <iq xmlns="jabber:client" id="cBpzpiDBlUWmZ5ABFOZ9tr0" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-09-08 22:44:46 |  >> <iq id="cBpzpiDBlUWmZ5ABFOZ9tr0" to="jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 << <iq id="cBpzpiDBlUWmZ5ABFOZ9tr0" to="jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337" type="result" xmlns="jabber:client"/>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-09-08 22:44:46 |  << <iq to="test-domain.com" id="HCq9MP8jX3kM2lN0rlaHzj7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 >> <iq to="test-domain.com" id="HCq9MP8jX3kM2lN0rlaHzj7" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-09-08 22:44:46 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-09-08 22:44:46 |  << <iq id="mSaIQ7HS7pZt49q12tfTbFn" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 >> <iq id="mSaIQ7HS7pZt49q12tfTbFn" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-09-08 22:44:46 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: connected->disconnecting

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.terminateStream: Terminating XMPP Stream

2022-09-08 22:44:46 |  >> <enabled resume="true" id="8476b811-eaef-4f21-a310-3845e07c53b1" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:46 | jaxmpp_ :: jaxmpp__1lob630564@test-domain.com/1608752850-tigase-337 << <enabled resume="true" id="8476b811-eaef-4f21-a310-3845e07c53b1" max="60" location="ed1b7dd8a1b3" xmlns="urn:xmpp:sm:3"/>

2022-09-08 22:44:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.connector.AbstractWebSocketConnector.setStage: Connector state changed: disconnecting->disconnected

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-09-08 22:44:46 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]