Test Results Report - XEP-0136 - Message Archive

Passed Tests
tigase.tests.archive.TestMessageArchive
testChangeArchiveSettings	2.726s	2020-05-26 01:02:11 \| 2020-05-26 01:02:11 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-26 01:02:11 \| ------------------------------------ 2020-05-26 01:02:11 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=575 thread=1] Connector state changed: connected->disconnecting 2020-05-26 01:02:11 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=575 thread=1] Terminating XMPP Stream 2020-05-26 01:02:11 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:11 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-26 01:02:11 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-26 01:02:12 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Connector state changed: null->connecting 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] DNS entry stored in session object: localhost:5222 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Preparing connection to [localhost:5222] 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Connector state changed: connecting->connected 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Whitespace ping period is setted to nullms 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3661] Proceeding TLS 2020-05-26 01:02:12 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3661] Start handshake 2020-05-26 01:02:14 \| [Mutex] received everything. 2020-05-26 01:02:14 \| [Mutex] isItemNotified: setArchiveSettings:275363b3-a014-4ac6-b0b5-ba80475517be:success :: true 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3661] Connector state changed: connected->disconnected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3661] Stream terminated 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testMessageArchiving	10.077s	2020-05-26 01:02:14 \| 2020-05-26 01:02:14 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-26 01:02:14 \| ------------------------------------ 2020-05-26 01:02:14 \| [Mutex] waiting for: [msg:71au030284] 2020-05-26 01:02:14 \| [Mutex] received everything. 2020-05-26 01:02:15 \| [Mutex] waiting for: [msg:50tsfp0286] 2020-05-26 01:02:15 \| [Mutex] received everything. 2020-05-26 01:02:16 \| [Mutex] waiting for: [msg:vtredp0288] 2020-05-26 01:02:16 \| [Mutex] received everything. 2020-05-26 01:02:17 \| [Mutex] waiting for: [msg:irzl4g0290] 2020-05-26 01:02:17 \| [Mutex] received everything. 2020-05-26 01:02:18 \| [Mutex] waiting for: [msg:qh64he0292] 2020-05-26 01:02:18 \| [Mutex] received everything. 2020-05-26 01:02:19 \| [Mutex] waiting for: [msg:2skc9v0294] 2020-05-26 01:02:19 \| [Mutex] received everything. 2020-05-26 01:02:20 \| [Mutex] waiting for: [msg:qckewf0296] 2020-05-26 01:02:20 \| [Mutex] received everything. 2020-05-26 01:02:21 \| [Mutex] waiting for: [msg:qvqk200298] 2020-05-26 01:02:21 \| [Mutex] received everything. 2020-05-26 01:02:22 \| [Mutex] waiting for: [msg:2xe2fa0300] 2020-05-26 01:02:22 \| [Mutex] received everything. 2020-05-26 01:02:23 \| [Mutex] waiting for: [msg:u55w9n0302] 2020-05-26 01:02:23 \| [Mutex] received everything.
testMesssageRetrivalJidComparison	0.027s	2020-05-26 01:02:24 \| 2020-05-26 01:02:24 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-26 01:02:24 \| ------------------------------------ 2020-05-26 01:02:24 \| [Mutex] waiting for: [1:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received, 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received] 2020-05-26 01:02:24 \| [Mutex] waiting for: [1:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received, 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received] 2020-05-26 01:02:24 \| [Mutex] received everything. 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:s0fhjp0283 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:mt7odu0285 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:iyh3g90287 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:1xa6p30289 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:3ze7mt0291 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:49p5qz0293 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:8sjkyl0295 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:m7wndh0297 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:6t1xbq0299 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:21460c27-5c8e-457c-a01b-ff83f9f0ca8e:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:jaiw620301 :: true
testMesssageRetrivalSuccess	0.033s	2020-05-26 01:02:24 \| 2020-05-26 01:02:24 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-26 01:02:24 \| ------------------------------------ 2020-05-26 01:02:24 \| [Mutex] waiting for: [2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received, 1:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received] 2020-05-26 01:02:24 \| [Mutex] waiting for: [2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received, 1:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:received] 2020-05-26 01:02:24 \| [Mutex] received everything. 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:s0fhjp0283 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:mt7odu0285 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:iyh3g90287 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:1xa6p30289 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:3ze7mt0291 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:49p5qz0293 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:8sjkyl0295 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:m7wndh0297 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:6t1xbq0299 :: true 2020-05-26 01:02:24 \| [Mutex] isItemNotified: 2:fe34f44e-ec3a-4794-9282-cd2c2b52865d:275363b3-a014-4ac6-b0b5-ba80475517be:retriveCollection:success:jaiw620301 :: true
testRemovalOnUserRemoval	3.421s	2020-05-26 01:02:24 \| 2020-05-26 01:02:24 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-26 01:02:24 \| ------------------------------------ 2020-05-26 01:02:24 \| !!!!!!!!! MaM-_s9n50d0281@localhost 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: null->connecting 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] DNS entry stored in session object: localhost:5222 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Preparing connection to [localhost:5222] 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: connecting->connected 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Whitespace ping period is setted to nullms 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3664] Start TLS 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Proceeding TLS 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Start handshake 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj03eFVrMVNtTHJicjluOUhsOHdoWg==</auth> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03eFVrMVNtTHJicjluOUhsOHdoWk15eFJnRGNvNUw5TlBZZFVuVjlHLHM9QjF6a0pPTEpreU5vdlE9PSxpPTQwOTY=</challenge> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9N3hVazFTbUxyYnI5bjlIbDh3aFpNeXhSZ0RjbzVMOU5QWWRVblY5RyxwPXJxR0M0SWVwOHJwVTNZTG9WYUhtWVpXL2tLcTZyMUF3VzFtbVh5QkFqcEk9</response> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1RM2prcGxrd2crSmI4RE5TdFRJcnpBMGpWd0FiVWk5dzZGcTkzbkZwQ21nPQ==</success> 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="WH0Cl3lQ7DAaHKAtgHLZgyt" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="WH0Cl3lQ7DAaHKAtgHLZgyt" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-275</jid></bind></iq> 2020-05-26 01:02:24 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq xmlns="jabber:client" id="OLaZdEkZ3jX8n7t6DFEiXgC" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="OLaZdEkZ3jX8n7t6DFEiXgC" xmlns="jabber:client" type="result"/> 2020-05-26 01:02:24 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq to="localhost" id="IGTcLGJUcFzmKPJFFydNPO3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <enabled resume="true" max="60" id="8c9e4ca7-9d83-4a12-a790-ccf4e5043b9f" location="9585556aacac" xmlns="urn:xmpp:sm:3"/> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq id="3p2P06vkP1viCBm5CCvfIsf" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" from="localhost" id="IGTcLGJUcFzmKPJFFydNPO3" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-26 01:02:24 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="3p2P06vkP1viCBm5CCvfIsf" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:25 \| Remove account as admin: MaM-_s9n50d0281@localhost 2020-05-26 01:02:25 \| [Mutex] waiting for: [account:removed] 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-274" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-275" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2020-05-26 01:02:25 \| [Mutex] received everything. 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Connector state changed: connected->disconnected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Stream terminated 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Using XEP-0077 mode!!!! 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: null->connecting 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@587ff84d 2020-05-26 01:02:25 \| StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=connecting}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}} 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] DNS entry stored in session object: localhost:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Preparing connection to [localhost:5222] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connecting->connected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Whitespace ping period is setted to nullms 2020-05-26 01:02:25 \| [Mutex] waiting for: [registration] 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@26587298 2020-05-26 01:02:25 \| StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=connected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=1}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}} 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@48a81315 2020-05-26 01:02:25 \| MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:25 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846} 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3671] Start TLS 2020-05-26 01:02:25 \| StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-05-26 01:02:25 \| MaM- :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-26 01:02:25 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3} 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Proceeding TLS 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Start handshake 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@3e3db08f 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@5b14d962 2020-05-26 01:02:25 \| MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:25 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae} 2020-05-26 01:02:25 \| StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-05-26 01:02:25 \| MaM- :: null >> <iq to="localhost" id="4Ob6iT6n7HGo9sh0o3lUQ17" type="get"><query xmlns="jabber:iq:register"/></iq> 2020-05-26 01:02:25 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@4257ea8b} 2020-05-26 01:02:25 \| MaM- :: null << <iq from="localhost" id="4Ob6iT6n7HGo9sh0o3lUQ17" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service. Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><x xmlns="jabber:x:data" type="form"><title>Account Registration</title><instructions>Choose a user name and password for use with this service. Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><field type="hidden" var="FORM_TYPE"><value>jabber:iq:register</value></field><field label="Username" type="text-single" var="username"><required/><value/></field><field label="Password" type="text-private" var="password"><required/><value/></field><field label="Email (MUST BE VALID!)" type="text-single" var="email"><required/><value/></field></x></query></iq> 2020-05-26 01:02:25 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@73e4e355} 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@200e8c6e 2020-05-26 01:02:25 \| MaM- :: null >> <iq to="localhost" id="1RiiAxqWbGxrlYYjMViYdFy" type="set"><query xmlns="jabber:iq:register"><username>MaM-_s9n50d0281</username><password>MaM-_s9n50d0281</password><email>e51e32af-72b9-4329-bc9b-bb9b91d6bd25@localhost</email></query></iq> 2020-05-26 01:02:25 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@6302e7ef} 2020-05-26 01:02:25 \| MaM- :: null << <iq from="localhost" id="1RiiAxqWbGxrlYYjMViYdFy" xmlns="jabber:client" type="result"/> 2020-05-26 01:02:25 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@5d2c8ec4} 2020-05-26 01:02:25 \| [Mutex] received everything. 2020-05-26 01:02:25 \| [Mutex] isItemNotified: registrationSuccess :: true 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connected->disconnecting 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Terminating XMPP Stream 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Connector state changed: disconnecting->disconnected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Stream terminated 2020-05-26 01:02:25 \| StateChangedEvent{oldState=connected, newState=disconnecting, sessionObject=AbstractSessionObject{properties={CONNECTOR#ENCRYPTED_KEY=Entry{scope=stream, value=true}, TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=2}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}} 2020-05-26 01:02:25 \| StateChangedEvent{oldState=disconnecting, newState=disconnected, sessionObject=AbstractSessionObject{properties={CONNECTOR#ENCRYPTED_KEY=Entry{scope=stream, value=true}, TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=2}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}} 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@6cfccde6 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@38d66567 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@6c6ff3ef 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@550c1a08 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@5f956eb2 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: null->connecting 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] DNS entry stored in session object: localhost:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Preparing connection to [localhost:5222] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connecting->connected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Whitespace ping period is setted to nullms 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3678] Start TLS 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Proceeding TLS 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Start handshake 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj0wVHR6b2MwekV3Q0Q5ZkJDSHByTA==</auth> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0wVHR6b2MwekV3Q0Q5ZkJDSHByTGtZZkNoSDhaM0tMVmNtTGVPUlZ5LHM9bGZwSjQ3aDREa21QQWc9PSxpPTQwOTY=</challenge> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MFR0em9jMHpFd0NEOWZCQ0hwckxrWWZDaEg4WjNLTFZjbUxlT1JWeSxwPTdFcmdrdnFIVSt4SURyanViOE5Eb2VRL2dtejRhRG5yRjV1VU11L29DT2s9</response> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13NjdJbGxJQk1Qbzdzd3UxN2N1NW96TFpFOHRTcEtsNUNwOERGUlFIcGIwPQ==</success> 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="dvc9sh0o3lUQ17rdEpbBm9H" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="dvc9sh0o3lUQ17rdEpbBm9H" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-276</jid></bind></iq> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq xmlns="jabber:client" id="vEB1rco9Eo5u9FNSMV0ncMP" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="vEB1rco9Eo5u9FNSMV0ncMP" xmlns="jabber:client" type="result"/> 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq to="localhost" id="hXuKLLKwnPR3kLT1cP3qQ3s" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <enabled resume="true" max="60" id="34df164d-601e-4d10-bc16-5067e2d387f3" location="9585556aacac" xmlns="urn:xmpp:sm:3"/> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq id="cO1shIN2sbBqS5fHJGtjRSb" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" from="localhost" id="hXuKLLKwnPR3kLT1cP3qQ3s" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="cO1shIN2sbBqS5fHJGtjRSb" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-276" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connected->disconnecting 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Terminating XMPP Stream 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Connector state changed: disconnecting->disconnected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Stream terminated 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: null->connecting 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] DNS entry stored in session object: localhost:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Preparing connection to [localhost:5222] 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: connecting->connected 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Whitespace ping period is setted to nullms 2020-05-26 01:02:25 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3678] Start TLS 2020-05-26 01:02:25 \| MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Proceeding TLS 2020-05-26 01:02:25 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Start handshake 2020-05-26 01:02:26 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-26 01:02:26 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-26 01:02:26 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj1oeUR6cnNzbmkwZE5VZjhpSlpSdg==</auth> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1oeUR6cnNzbmkwZE5VZjhpSlpSdnJjTFdEb05YZ0VUQnZrWDBWQkp4LHM9bGZwSjQ3aDREa21QQWc9PSxpPTQwOTY=</challenge> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aHlEenJzc25pMGROVWY4aUpaUnZyY0xXRG9OWGdFVEJ2a1gwVkJKeCxwPXk2ekxYLzhmR0lJTWxTK1VKYTQ0WVdMdWpJZWtWSDQ0VlFtN1NRU0pGNHM9</response> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13M3VhUjRJNDBPTXVtNjJUOGZzMEx1YTRFdGFyeFRMSHBScm9WYStacDZZPQ==</success> 2020-05-26 01:02:26 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="yR8EFGsdKFHR02r2rk2lV4a" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="yR8EFGsdKFHR02r2rk2lV4a" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-277</jid></bind></iq> 2020-05-26 01:02:26 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq xmlns="jabber:client" id="YFwNPO3vkEFGsdKFHR02r2r" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="YFwNPO3vkEFGsdKFHR02r2r" xmlns="jabber:client" type="result"/> 2020-05-26 01:02:26 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq to="localhost" id="jQ1Fy8BhLRWZlV0rhSKR4eL" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <enabled resume="true" max="60" id="0d921e53-07b5-484f-9347-297d6e4b3dc8" location="9585556aacac" xmlns="urn:xmpp:sm:3"/> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="TVeV3r6jZdABBw2reyp8rfX" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" from="localhost" id="jQ1Fy8BhLRWZlV0rhSKR4eL" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="TVeV3r6jZdABBw2reyp8rfX" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="sIzT1cP3qQ3s0jW3tp6ePOZ" type="get"><auto xmlns="urn:xmpp:archive" save="true"/></iq> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-277" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence> 2020-05-26 01:02:26 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="sIzT1cP3qQ3s0jW3tp6ePOZ" xmlns="jabber:client" type="result"><auto xmlns="urn:xmpp:archive" save="true"/></iq> 2020-05-26 01:02:28 \| [Mutex] received everything. 2020-05-26 01:02:28 \| [Mutex] isItemNotified: setArchiveSettings:2383006e-8cdf-479b-aa44-d9046b873cd7:success :: true 2020-05-26 01:02:28 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="w3yT6cLSQZiLTfMAxeP6Ar7" type="get"><retrieve xmlns="urn:xmpp:archive" with="MaM-_opb3t90282@localhost"><set xmlns="http://jabber.org/protocol/rsm"/></retrieve></iq> 2020-05-26 01:02:28 \| [Mutex] waiting for: [1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:received, 1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:count:0] 2020-05-26 01:02:28 \| MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="w3yT6cLSQZiLTfMAxeP6Ar7" xmlns="jabber:client" type="result"><chat xmlns="urn:xmpp:archive" with="MaM-_opb3t90282@localhost"/></iq> 2020-05-26 01:02:28 \| [Mutex] received everything. 2020-05-26 01:02:28 \| [Mutex] isItemNotified: 1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:count:0 :: true 2020-05-26 01:02:28 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Connector state changed: connected->disconnected 2020-05-26 01:02:28 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Stream terminated 2020-05-26 01:02:28 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-26 01:02:28 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:24 |

2020-05-26 01:02:24 | null / [TestClass name=class tigase.tests.archive.TestMessageArchive]

2020-05-26 01:02:24 | ------------------------------------

2020-05-26 01:02:24 | !!!!!!!!! MaM-_s9n50d0281@localhost

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: null->connecting

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] DNS entry stored in session object: localhost:5222

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Preparing connection to [localhost:5222]

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: connecting->connected

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Whitespace ping period is setted to nullms

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3664] Start TLS

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Proceeding TLS

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Start handshake

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj03eFVrMVNtTHJicjluOUhsOHdoWg==</auth>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03eFVrMVNtTHJicjluOUhsOHdoWk15eFJnRGNvNUw5TlBZZFVuVjlHLHM9QjF6a0pPTEpreU5vdlE9PSxpPTQwOTY=</challenge>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9N3hVazFTbUxyYnI5bjlIbDh3aFpNeXhSZ0RjbzVMOU5QWWRVblY5RyxwPXJxR0M0SWVwOHJwVTNZTG9WYUhtWVpXL2tLcTZyMUF3VzFtbVh5QkFqcEk9</response>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1RM2prcGxrd2crSmI4RE5TdFRJcnpBMGpWd0FiVWk5dzZGcTkzbkZwQ21nPQ==</success>

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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 ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="WH0Cl3lQ7DAaHKAtgHLZgyt" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="WH0Cl3lQ7DAaHKAtgHLZgyt" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-275</jid></bind></iq>

2020-05-26 01:02:24 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq xmlns="jabber:client" id="OLaZdEkZ3jX8n7t6DFEiXgC" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="OLaZdEkZ3jX8n7t6DFEiXgC" xmlns="jabber:client" type="result"/>

2020-05-26 01:02:24 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq to="localhost" id="IGTcLGJUcFzmKPJFFydNPO3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <enabled resume="true" max="60" id="8c9e4ca7-9d83-4a12-a790-ccf4e5043b9f" location="9585556aacac" xmlns="urn:xmpp:sm:3"/>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <iq id="3p2P06vkP1viCBm5CCvfIsf" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" from="localhost" id="IGTcLGJUcFzmKPJFFydNPO3" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2020-05-26 01:02:24 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-275" id="3p2P06vkP1viCBm5CCvfIsf" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:25 | Remove account as admin: MaM-_s9n50d0281@localhost

2020-05-26 01:02:25 | [Mutex] waiting for: [account:removed]

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-274" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-275 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-275" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2020-05-26 01:02:25 | [Mutex] received everything.

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Connector state changed: connected->disconnected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3666] Stream terminated

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Using XEP-0077 mode!!!!

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: null->connecting

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@587ff84d

2020-05-26 01:02:25 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=connecting}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}}

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] DNS entry stored in session object: localhost:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Preparing connection to [localhost:5222]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connecting->connected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Whitespace ping period is setted to nullms

2020-05-26 01:02:25 | [Mutex] waiting for: [registration]

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@26587298

2020-05-26 01:02:25 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=connected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=1}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}}

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@48a81315

2020-05-26 01:02:25 | MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:25 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846}

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3671] Start TLS

2020-05-26 01:02:25 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2020-05-26 01:02:25 | MaM- :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-26 01:02:25 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Proceeding TLS

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Start handshake

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@3e3db08f

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@5b14d962

2020-05-26 01:02:25 | MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:25 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae}

2020-05-26 01:02:25 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2020-05-26 01:02:25 | MaM- :: null >> <iq to="localhost" id="4Ob6iT6n7HGo9sh0o3lUQ17" type="get"><query xmlns="jabber:iq:register"/></iq>

2020-05-26 01:02:25 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@4257ea8b}

2020-05-26 01:02:25 | MaM- :: null << <iq from="localhost" id="4Ob6iT6n7HGo9sh0o3lUQ17" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.

Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><x xmlns="jabber:x:data" type="form"><title>Account Registration</title><instructions>Choose a user name and password for use with this service.

Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><field type="hidden" var="FORM_TYPE"><value>jabber:iq:register</value></field><field label="Username" type="text-single" var="username"><required/><value/></field><field label="Password" type="text-private" var="password"><required/><value/></field><field label="Email (MUST BE VALID!)" type="text-single" var="email"><required/><value/></field></x></query></iq>

2020-05-26 01:02:25 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@73e4e355}

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@200e8c6e

2020-05-26 01:02:25 | MaM- :: null >> <iq to="localhost" id="1RiiAxqWbGxrlYYjMViYdFy" type="set"><query xmlns="jabber:iq:register"><username>MaM-_s9n50d0281</username><password>MaM-_s9n50d0281</password><email>e51e32af-72b9-4329-bc9b-bb9b91d6bd25@localhost</email></query></iq>

2020-05-26 01:02:25 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@6302e7ef}

2020-05-26 01:02:25 | MaM- :: null << <iq from="localhost" id="1RiiAxqWbGxrlYYjMViYdFy" xmlns="jabber:client" type="result"/>

2020-05-26 01:02:25 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@5d2c8ec4}

2020-05-26 01:02:25 | [Mutex] received everything.

2020-05-26 01:02:25 | [Mutex] isItemNotified: registrationSuccess :: true

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connected->disconnecting

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Terminating XMPP Stream

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Connector state changed: disconnecting->disconnected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3673] Stream terminated

2020-05-26 01:02:25 | StateChangedEvent{oldState=connected, newState=disconnecting, sessionObject=AbstractSessionObject{properties={CONNECTOR#ENCRYPTED_KEY=Entry{scope=stream, value=true}, TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=2}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}}

2020-05-26 01:02:25 | StateChangedEvent{oldState=disconnecting, newState=disconnected, sessionObject=AbstractSessionObject{properties={CONNECTOR#ENCRYPTED_KEY=Entry{scope=stream, value=true}, TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@6608790}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@77aaf846, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@53ac59ae]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@56b80d32}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@475d8dab}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnected}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@5287933d}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@17850709}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@309f3824}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=2}, LOG_PREFIX=Entry{scope=user, value=MaM-}, socket#ServerHost=Entry{scope=user, value=localhost}, IN_BAND_REGISTRATION_MODE_KEY=Entry{scope=session, value=true}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Tue May 26 01:02:25 PDT 2020}}}}

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@6cfccde6

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@38d66567

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@6c6ff3ef

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@550c1a08

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@5f956eb2

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: null->connecting

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] DNS entry stored in session object: localhost:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Preparing connection to [localhost:5222]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connecting->connected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Whitespace ping period is setted to nullms

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3678] Start TLS

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Proceeding TLS

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Start handshake

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj0wVHR6b2MwekV3Q0Q5ZkJDSHByTA==</auth>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0wVHR6b2MwekV3Q0Q5ZkJDSHByTGtZZkNoSDhaM0tMVmNtTGVPUlZ5LHM9bGZwSjQ3aDREa21QQWc9PSxpPTQwOTY=</challenge>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MFR0em9jMHpFd0NEOWZCQ0hwckxrWWZDaEg4WjNLTFZjbUxlT1JWeSxwPTdFcmdrdnFIVSt4SURyanViOE5Eb2VRL2dtejRhRG5yRjV1VU11L29DT2s9</response>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13NjdJbGxJQk1Qbzdzd3UxN2N1NW96TFpFOHRTcEtsNUNwOERGUlFIcGIwPQ==</success>

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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 ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="dvc9sh0o3lUQ17rdEpbBm9H" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="dvc9sh0o3lUQ17rdEpbBm9H" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-276</jid></bind></iq>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq xmlns="jabber:client" id="vEB1rco9Eo5u9FNSMV0ncMP" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="vEB1rco9Eo5u9FNSMV0ncMP" xmlns="jabber:client" type="result"/>

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq to="localhost" id="hXuKLLKwnPR3kLT1cP3qQ3s" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <enabled resume="true" max="60" id="34df164d-601e-4d10-bc16-5067e2d387f3" location="9585556aacac" xmlns="urn:xmpp:sm:3"/>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <iq id="cO1shIN2sbBqS5fHJGtjRSb" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" from="localhost" id="hXuKLLKwnPR3kLT1cP3qQ3s" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-276" id="cO1shIN2sbBqS5fHJGtjRSb" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-276 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-276" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connected->disconnecting

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Terminating XMPP Stream

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Connector state changed: disconnecting->disconnected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3680] Stream terminated

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: null->connecting

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] DNS entry stored in session object: localhost:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Preparing connection to [localhost:5222]

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: connecting->connected

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Whitespace ping period is setted to nullms

2020-05-26 01:02:25 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3678] Start TLS

2020-05-26 01:02:25 | MaM- :: MaM-_s9n50d0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Proceeding TLS

2020-05-26 01:02:25 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Start handshake

2020-05-26 01:02:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-05-26 01:02:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-26 01:02:26 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3M5bjUwZDAyODEscj1oeUR6cnNzbmkwZE5VZjhpSlpSdg==</auth>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1oeUR6cnNzbmkwZE5VZjhpSlpSdnJjTFdEb05YZ0VUQnZrWDBWQkp4LHM9bGZwSjQ3aDREa21QQWc9PSxpPTQwOTY=</challenge>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aHlEenJzc25pMGROVWY4aUpaUnZyY0xXRG9OWGdFVEJ2a1gwVkJKeCxwPXk2ekxYLzhmR0lJTWxTK1VKYTQ0WVdMdWpJZWtWSDQ0VlFtN1NRU0pGNHM9</response>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj13M3VhUjRJNDBPTXVtNjJUOGZzMEx1YTRFdGFyeFRMSHBScm9WYStacDZZPQ==</success>

2020-05-26 01:02:26 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><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 ver="z4E8/RbDarYCAPa9avxwCC7J86E=" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server"/></features>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost >> <iq xmlns="jabber:client" id="yR8EFGsdKFHR02r2rk2lV4a" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="yR8EFGsdKFHR02r2rk2lV4a" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_s9n50d0281@localhost/2095755800-tigase-277</jid></bind></iq>

2020-05-26 01:02:26 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq xmlns="jabber:client" id="YFwNPO3vkEFGsdKFHR02r2r" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="YFwNPO3vkEFGsdKFHR02r2r" xmlns="jabber:client" type="result"/>

2020-05-26 01:02:26 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq to="localhost" id="jQ1Fy8BhLRWZlV0rhSKR4eL" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <enabled resume="true" max="60" id="0d921e53-07b5-484f-9347-297d6e4b3dc8" location="9585556aacac" xmlns="urn:xmpp:sm:3"/>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="TVeV3r6jZdABBw2reyp8rfX" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" from="localhost" id="jQ1Fy8BhLRWZlV0rhSKR4eL" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10741/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="TVeV3r6jZdABBw2reyp8rfX" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="sIzT1cP3qQ3s0jW3tp6ePOZ" type="get"><auto xmlns="urn:xmpp:archive" save="true"/></iq>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <presence to="MaM-_s9n50d0281@localhost" from="MaM-_s9n50d0281@localhost/2095755800-tigase-277" xmlns="jabber:client"><c ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps"/></presence>

2020-05-26 01:02:26 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="sIzT1cP3qQ3s0jW3tp6ePOZ" xmlns="jabber:client" type="result"><auto xmlns="urn:xmpp:archive" save="true"/></iq>

2020-05-26 01:02:28 | [Mutex] received everything.

2020-05-26 01:02:28 | [Mutex] isItemNotified: setArchiveSettings:2383006e-8cdf-479b-aa44-d9046b873cd7:success :: true

2020-05-26 01:02:28 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 >> <iq id="w3yT6cLSQZiLTfMAxeP6Ar7" type="get"><retrieve xmlns="urn:xmpp:archive" with="MaM-_opb3t90282@localhost"><set xmlns="http://jabber.org/protocol/rsm"/></retrieve></iq>

2020-05-26 01:02:28 | [Mutex] waiting for: [1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:received, 1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:count:0]

2020-05-26 01:02:28 | MaM- :: MaM-_s9n50d0281@localhost/2095755800-tigase-277 << <iq to="MaM-_s9n50d0281@localhost/2095755800-tigase-277" id="w3yT6cLSQZiLTfMAxeP6Ar7" xmlns="jabber:client" type="result"><chat xmlns="urn:xmpp:archive" with="MaM-_opb3t90282@localhost"/></iq>

2020-05-26 01:02:28 | [Mutex] received everything.

2020-05-26 01:02:28 | [Mutex] isItemNotified: 1:1f560186-674b-4a4b-861d-fe790e69fb78:2383006e-8cdf-479b-aa44-d9046b873cd7:retriveCollection:count:0 :: true

2020-05-26 01:02:28 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Connector state changed: connected->disconnected

2020-05-26 01:02:28 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3686] Stream terminated

2020-05-26 01:02:28 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-26 01:02:28 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

XEP-0136 - Message Archive - extended tests