Test Results Report - XEP-0136 - Message Archive

Passed Tests
tigase.tests.archive.TestMessageArchive
testChangeArchiveSettings	2.731s	2020-05-19 17:46:30 \| 2020-05-19 17:46:30 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-19 17:46:30 \| ------------------------------------ 2020-05-19 17:46:30 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=575 thread=1] Connector state changed: connected->disconnecting 2020-05-19 17:46:30 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=575 thread=1] Terminating XMPP Stream 2020-05-19 17:46:30 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:30 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-19 17:46:30 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-19 17:46:31 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Connector state changed: null->connecting 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] DNS entry stored in session object: localhost:5222 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Preparing connection to [localhost:5222] 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Connector state changed: connecting->connected 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=1] Whitespace ping period is setted to nullms 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3660] Proceeding TLS 2020-05-19 17:46:31 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3660] Start handshake 2020-05-19 17:46:33 \| [Mutex] received everything. 2020-05-19 17:46:33 \| [Mutex] isItemNotified: setArchiveSettings:c1d156f0-6ff0-4163-bd5e-610c26b78bef:success :: true 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3660] Connector state changed: connected->disconnected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=578 thread=3660] Stream terminated 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted
testMessageArchiving	10.076s	2020-05-19 17:46:33 \| 2020-05-19 17:46:33 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-19 17:46:33 \| ------------------------------------ 2020-05-19 17:46:33 \| [Mutex] waiting for: [msg:1310al0284] 2020-05-19 17:46:33 \| [Mutex] received everything. 2020-05-19 17:46:34 \| [Mutex] waiting for: [msg:4iatou0286] 2020-05-19 17:46:34 \| [Mutex] received everything. 2020-05-19 17:46:35 \| [Mutex] waiting for: [msg:sybsnm0288] 2020-05-19 17:46:35 \| [Mutex] received everything. 2020-05-19 17:46:36 \| [Mutex] waiting for: [msg:ddoiug0290] 2020-05-19 17:46:36 \| [Mutex] received everything. 2020-05-19 17:46:37 \| [Mutex] waiting for: [msg:af44ll0292] 2020-05-19 17:46:37 \| [Mutex] received everything. 2020-05-19 17:46:38 \| [Mutex] waiting for: [msg:fx0dwn0294] 2020-05-19 17:46:38 \| [Mutex] received everything. 2020-05-19 17:46:39 \| [Mutex] waiting for: [msg:2xm7ze0296] 2020-05-19 17:46:39 \| [Mutex] received everything. 2020-05-19 17:46:40 \| [Mutex] waiting for: [msg:h7l6020298] 2020-05-19 17:46:40 \| [Mutex] received everything. 2020-05-19 17:46:41 \| [Mutex] waiting for: [msg:t7cdh20300] 2020-05-19 17:46:41 \| [Mutex] received everything. 2020-05-19 17:46:42 \| [Mutex] waiting for: [msg:psmspa0302] 2020-05-19 17:46:42 \| [Mutex] received everything.
testMesssageRetrivalJidComparison	0.022s	2020-05-19 17:46:43 \| 2020-05-19 17:46:43 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-19 17:46:43 \| ------------------------------------ 2020-05-19 17:46:43 \| [Mutex] waiting for: [1:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received, 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received] 2020-05-19 17:46:43 \| [Mutex] waiting for: [1:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received, 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received] 2020-05-19 17:46:43 \| [Mutex] received everything. 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:hiohc10283 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:iwepyk0285 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:sinvsc0287 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qy4hqr0289 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:957sti0291 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:cww0m30293 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:96irjz0295 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qe383o0297 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:871twg0299 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:ef017646-0a85-4758-b5fb-dc00f30f6227:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qcwmk90301 :: true
testMesssageRetrivalSuccess	0.016s	2020-05-19 17:46:43 \| 2020-05-19 17:46:43 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-19 17:46:43 \| ------------------------------------ 2020-05-19 17:46:43 \| [Mutex] waiting for: [1:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received, 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received] 2020-05-19 17:46:43 \| [Mutex] waiting for: [1:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received, 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:received] 2020-05-19 17:46:43 \| [Mutex] received everything. 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:hiohc10283 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:iwepyk0285 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:sinvsc0287 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qy4hqr0289 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:957sti0291 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:cww0m30293 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:96irjz0295 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qe383o0297 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:871twg0299 :: true 2020-05-19 17:46:43 \| [Mutex] isItemNotified: 2:f88e28cb-c9c2-4ff7-a8a6-bb0b604773fe:c1d156f0-6ff0-4163-bd5e-610c26b78bef:retriveCollection:success:qcwmk90301 :: true
testRemovalOnUserRemoval	3.538s	2020-05-19 17:46:43 \| 2020-05-19 17:46:43 \| null / [TestClass name=class tigase.tests.archive.TestMessageArchive] 2020-05-19 17:46:43 \| ------------------------------------ 2020-05-19 17:46:43 \| !!!!!!!!! MaM-_qj2nbo0281@localhost 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: null->connecting 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] DNS entry stored in session object: localhost:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Preparing connection to [localhost:5222] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: connecting->connected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Whitespace ping period is setted to nullms 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3663] Start TLS 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Proceeding TLS 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Start handshake 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj1UeDdubWhvbEd1UFdNaGs3RFhjWQ==</auth> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1UeDdubWhvbEd1UFdNaGs3RFhjWUFmSlhBeFZHZjlmNXdsYTBYR0tpLHM9cFd6UkpxV2dpSzdTaVE9PSxpPTQwOTY=</challenge> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VHg3bm1ob2xHdVBXTWhrN0RYY1lBZkpYQXhWR2Y5ZjV3bGEwWEdLaSxwPWh3Q0RtY0JHUG8wQTNqSGNTQ1lQRDRPNG9UNTZiZUwrZ2UyWEpWQUk2bWc9</response> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1yWC9aNVVsa054N1J0dzh3R2N1YlZHcHUrMTF1MldiU21YSE1QN2YyclY0PQ==</success> 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="yF8RRR1jVj3hBEsmdLMIAtr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="yF8RRR1jVj3hBEsmdLMIAtr" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-274</jid></bind></iq> 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq xmlns="jabber:client" id="MLovgGwlbIAyu9zr1hYaKHx" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <iq xmlns="jabber:client" id="MLovgGwlbIAyu9zr1hYaKHx" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"/> 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq to="localhost" id="4qldJJLZpZ1u8IVbCoWbR4i" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <enabled xmlns="urn:xmpp:sm:3" id="36d2722e-8915-4ddf-b651-71ac506336ab" location="9585556aacac" max="60" resume="true"/> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq id="iyHW2nfQQ4wdHHxj2i0dzhR" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <iq xmlns="jabber:client" id="4qldJJLZpZ1u8IVbCoWbR4i" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <iq xmlns="jabber:client" id="iyHW2nfQQ4wdHHxj2i0dzhR" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-273"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-274"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence> 2020-05-19 17:46:43 \| Remove account as admin: MaM-_qj2nbo0281@localhost 2020-05-19 17:46:43 \| [Mutex] waiting for: [account:removed] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Connector state changed: connected->disconnected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Stream terminated 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| [Mutex] received everything. 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Using XEP-0077 mode!!!! 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: null->connecting 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@36b59e5e 2020-05-19 17:46:43 \| StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@7eec431e}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}} 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] DNS entry stored in session object: localhost:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Preparing connection to [localhost:5222] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connecting->connected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Whitespace ping period is setted to nullms 2020-05-19 17:46:43 \| [Mutex] waiting for: [registration] 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@487c25f6 2020-05-19 17:46:43 \| StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}} 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@1bcb5d52 2020-05-19 17:46:43 \| MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c} 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3670] Start TLS 2020-05-19 17:46:43 \| StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-05-19 17:46:43 \| MaM- :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-19 17:46:43 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3} 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Proceeding TLS 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Start handshake 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@6855def0 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@412f1599 2020-05-19 17:46:43 \| MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123} 2020-05-19 17:46:43 \| StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-05-19 17:46:43 \| MaM- :: null >> <iq to="localhost" id="cn62eUjXau8zDCoaIBtfDvq" type="get"><query xmlns="jabber:iq:register"/></iq> 2020-05-19 17:46:43 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@8f2a3ba0} 2020-05-19 17:46:43 \| MaM- :: null << <iq xmlns="jabber:client" id="cn62eUjXau8zDCoaIBtfDvq" from="localhost" 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 var="FORM_TYPE" type="hidden"><value>jabber:iq:register</value></field><field var="username" label="Username" type="text-single"><required/><value/></field><field var="password" label="Password" type="text-private"><required/><value/></field><field var="email" label="Email (MUST BE VALID!)" type="text-single"><required/><value/></field></x></query></iq> 2020-05-19 17:46:43 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@1d2e7e55} 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@5b4a815e 2020-05-19 17:46:43 \| MaM- :: null >> <iq to="localhost" id="8ETDHzbOM0vlW2eL1oWgUZf" type="set"><query xmlns="jabber:iq:register"><username>MaM-_qj2nbo0281</username><password>MaM-_qj2nbo0281</password><email>1901d401-0974-4986-bdff-861317e6d38b@localhost</email></query></iq> 2020-05-19 17:46:43 \| StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@f79bbdb9} 2020-05-19 17:46:43 \| MaM- :: null << <iq xmlns="jabber:client" id="8ETDHzbOM0vlW2eL1oWgUZf" from="localhost" type="result"/> 2020-05-19 17:46:43 \| StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@62f0c473} 2020-05-19 17:46:43 \| [Mutex] received everything. 2020-05-19 17:46:43 \| [Mutex] isItemNotified: registrationSuccess :: true 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connected->disconnecting 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Terminating XMPP Stream 2020-05-19 17:46:43 \| 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;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnecting}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}} 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Connector state changed: disconnecting->disconnected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Stream terminated 2020-05-19 17:46:43 \| 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;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}} 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1d4977e7 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@73fb7ecd 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@7c34419d 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1883d134 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@4e237b8c 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: null->connecting 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] DNS entry stored in session object: localhost:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Preparing connection to [localhost:5222] 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connecting->connected 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Whitespace ping period is setted to nullms 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3677] Start TLS 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Proceeding TLS 2020-05-19 17:46:43 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Start handshake 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-19 17:46:43 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj1jR2pNZ1FoVEdycGtyWUpQS1o2RA==</auth> 2020-05-19 17:46:43 \| MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jR2pNZ1FoVEdycGtyWUpQS1o2RFFLRkxSdnlCYXhKOFFYdE1TVTZ4LHM9VzQwUzB1YnZjRHVNdlE9PSxpPTQwOTY=</challenge> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y0dqTWdRaFRHcnBrcllKUEtaNkRRS0ZMUnZ5QmF4SjhRWHRNU1U2eCxwPUx5UTZrODFoRmtGNVdLd3hrV1RUL0E0L2dwcG82cmZKVDlsWWdYY3lzcDA9</response> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1CK3FZN1RVVEQrOGRiZDlZSzZPRUVKVGdXMTJ6RitaZC9zbFYxVFJHZHE4PQ==</success> 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="KwtM3r8q0j7Ak2s5rbvfU2u" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="KwtM3r8q0j7Ak2s5rbvfU2u" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-275</jid></bind></iq> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq xmlns="jabber:client" id="yNg7nT5rbP5lJUYohT2eCxz" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <iq xmlns="jabber:client" id="yNg7nT5rbP5lJUYohT2eCxz" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"/> 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq to="localhost" id="nK5IO0bDzhN0eSX3fNR1vr3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <iq xmlns="jabber:client" id="nK5IO0bDzhN0eSX3fNR1vr3" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <enabled xmlns="urn:xmpp:sm:3" id="93225a60-a070-45e5-abb9-6565c4cfdf30" location="9585556aacac" max="60" resume="true"/> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq id="DGRphZ9l8qi5vgGwlbIAyu9" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@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-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <iq xmlns="jabber:client" id="DGRphZ9l8qi5vgGwlbIAyu9" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connected->disconnecting 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Terminating XMPP Stream 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-275"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Connector state changed: disconnecting->disconnected 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Stream terminated 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: null->connecting 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] DNS entry stored in session object: localhost:5222 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Preparing connection to [localhost:5222] 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: connecting->connected 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Whitespace ping period is setted to nullms 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3677] Start TLS 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Proceeding TLS 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Start handshake 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj04YzdRZThuYlE4UFJTZVFYQ09zUQ==</auth> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj04YzdRZThuYlE4UFJTZVFYQ09zUUE5RHNzRURjeVBDUlFnSThuTThXLHM9VzQwUzB1YnZjRHVNdlE9PSxpPTQwOTY=</challenge> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OGM3UWU4bmJROFBSU2VRWENPc1FBOURzc0VEY3lQQ1JRZ0k4bk04VyxwPTNPTUZUZHQyRE5ON2JCNjAxZGJ6V2hSM3U3MlFlM05CaU5HSnN3NWdiekk9</response> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1rZkxPMk1JTlc4ZVFycnpTcmJUWlVEN2NTcndFazJnSkdZT2pkSDMzeXg0PQ==</success> 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="zDGTV9zrl7pRauql1iKEBHK" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="zDGTV9zrl7pRauql1iKEBHK" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-276</jid></bind></iq> 2020-05-19 17:46:44 \| tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq xmlns="jabber:client" id="eByWn5q0aDLSUcMJJLFzyk4" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="eByWn5q0aDLSUcMJJLFzyk4" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"/> 2020-05-19 17:46:44 \| tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq to="localhost" id="QCzP9EsiY7vjLLW6Cr1bFBv" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <enabled xmlns="urn:xmpp:sm:3" id="0fba2aee-f689-45cc-ba05-ec474947bc3c" location="9585556aacac" max="60" resume="true"/> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="rEDV9r7Ews7nT5rbP5lJUYo" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="QCzP9EsiY7vjLLW6Cr1bFBv" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" from="localhost" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity category="component" type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><identity category="server" type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10734/15d7a283"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/><feature var="jabber:iq:private"/></query></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@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-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="rEDV9r7Ews7nT5rbP5lJUYo" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><query xmlns="jabber:iq:roster"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-276"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="uDyJSYmVZ7xlZrjNDIACJNQ" type="get"><auto xmlns="urn:xmpp:archive" save="true"/></iq> 2020-05-19 17:46:44 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="uDyJSYmVZ7xlZrjNDIACJNQ" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><auto xmlns="urn:xmpp:archive" save="true"/></iq> 2020-05-19 17:46:46 \| [Mutex] received everything. 2020-05-19 17:46:46 \| [Mutex] isItemNotified: setArchiveSettings:b0459601-636b-4323-9fbf-82ae877976ba:success :: true 2020-05-19 17:46:46 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="xJOq3fDzp7HPPHQQ8LRY6m1" type="get"><retrieve xmlns="urn:xmpp:archive" with="MaM-_e7y2j10282@localhost"><set xmlns="http://jabber.org/protocol/rsm"/></retrieve></iq> 2020-05-19 17:46:46 \| [Mutex] waiting for: [1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:count:0, 1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:received] 2020-05-19 17:46:46 \| MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="xJOq3fDzp7HPPHQQ8LRY6m1" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><chat xmlns="urn:xmpp:archive" with="MaM-_e7y2j10282@localhost"/></iq> 2020-05-19 17:46:46 \| [Mutex] received everything. 2020-05-19 17:46:46 \| [Mutex] isItemNotified: 1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:count:0 :: true 2020-05-19 17:46:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Connector state changed: connected->disconnected 2020-05-19 17:46:46 \| tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Stream terminated 2020-05-19 17:46:46 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-05-19 17:46:46 \| tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 |

2020-05-19 17:46:43 | null / [TestClass name=class tigase.tests.archive.TestMessageArchive]

2020-05-19 17:46:43 | ------------------------------------

2020-05-19 17:46:43 | !!!!!!!!! MaM-_qj2nbo0281@localhost

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: null->connecting

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] DNS entry stored in session object: localhost:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Preparing connection to [localhost:5222]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Connector state changed: connecting->connected

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=1] Whitespace ping period is setted to nullms

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><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-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3663] Start TLS

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Proceeding TLS

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Start handshake

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

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

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj1UeDdubWhvbEd1UFdNaGs3RFhjWQ==</auth>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1UeDdubWhvbEd1UFdNaGs3RFhjWUFmSlhBeFZHZjlmNXdsYTBYR0tpLHM9cFd6UkpxV2dpSzdTaVE9PSxpPTQwOTY=</challenge>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VHg3bm1ob2xHdVBXTWhrN0RYY1lBZkpYQXhWR2Y5ZjV3bGEwWEdLaSxwPWh3Q0RtY0JHUG8wQTNqSGNTQ1lQRDRPNG9UNTZiZUwrZ2UyWEpWQUk2bWc9</response>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1yWC9aNVVsa054N1J0dzh3R2N1YlZHcHUrMTF1MldiU21YSE1QN2YyclY0PQ==</success>

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@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 xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="yF8RRR1jVj3hBEsmdLMIAtr" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="yF8RRR1jVj3hBEsmdLMIAtr" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-274</jid></bind></iq>

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq xmlns="jabber:client" id="MLovgGwlbIAyu9zr1hYaKHx" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <iq xmlns="jabber:client" id="MLovgGwlbIAyu9zr1hYaKHx" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"/>

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq to="localhost" id="4qldJJLZpZ1u8IVbCoWbR4i" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <enabled xmlns="urn:xmpp:sm:3" id="36d2722e-8915-4ddf-b651-71ac506336ab" location="9585556aacac" max="60" resume="true"/>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <iq id="iyHW2nfQQ4wdHHxj2i0dzhR" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <iq xmlns="jabber:client" id="iyHW2nfQQ4wdHHxj2i0dzhR" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-274" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-273"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-274 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-274"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-05-19 17:46:43 | Remove account as admin: MaM-_qj2nbo0281@localhost

2020-05-19 17:46:43 | [Mutex] waiting for: [account:removed]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Connector state changed: connected->disconnected

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=579 thread=3665] Stream terminated

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 | [Mutex] received everything.

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Using XEP-0077 mode!!!!

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: null->connecting

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@36b59e5e

2020-05-19 17:46:43 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@7eec431e}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}}

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] DNS entry stored in session object: localhost:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Preparing connection to [localhost:5222]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connecting->connected

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Whitespace ping period is setted to nullms

2020-05-19 17:46:43 | [Mutex] waiting for: [registration]

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@487c25f6

2020-05-19 17:46:43 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}}

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@1bcb5d52

2020-05-19 17:46:43 | MaM- :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><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-19 17:46:43 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c}

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3670] Start TLS

2020-05-19 17:46:43 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><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-19 17:46:43 | MaM- :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-19 17:46:43 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Proceeding TLS

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Start handshake

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@6855def0

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@412f1599

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

2020-05-19 17:46:43 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123}

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

2020-05-19 17:46:43 | MaM- :: null >> <iq to="localhost" id="cn62eUjXau8zDCoaIBtfDvq" type="get"><query xmlns="jabber:iq:register"/></iq>

2020-05-19 17:46:43 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@8f2a3ba0}

2020-05-19 17:46:43 | MaM- :: null << <iq xmlns="jabber:client" id="cn62eUjXau8zDCoaIBtfDvq" from="localhost" 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 var="FORM_TYPE" type="hidden"><value>jabber:iq:register</value></field><field var="username" label="Username" type="text-single"><required/><value/></field><field var="password" label="Password" type="text-private"><required/><value/></field><field var="email" label="Email (MUST BE VALID!)" type="text-single"><required/><value/></field></x></query></iq>

2020-05-19 17:46:43 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@1d2e7e55}

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@5b4a815e

2020-05-19 17:46:43 | MaM- :: null >> <iq to="localhost" id="8ETDHzbOM0vlW2eL1oWgUZf" type="set"><query xmlns="jabber:iq:register"><username>MaM-_qj2nbo0281</username><password>MaM-_qj2nbo0281</password><email>1901d401-0974-4986-bdff-861317e6d38b@localhost</email></query></iq>

2020-05-19 17:46:43 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@f79bbdb9}

2020-05-19 17:46:43 | MaM- :: null << <iq xmlns="jabber:client" id="8ETDHzbOM0vlW2eL1oWgUZf" from="localhost" type="result"/>

2020-05-19 17:46:43 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@62f0c473}

2020-05-19 17:46:43 | [Mutex] received everything.

2020-05-19 17:46:43 | [Mutex] isItemNotified: registrationSuccess :: true

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Connector state changed: connected->disconnecting

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=1] Terminating XMPP Stream

2020-05-19 17:46:43 | 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;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, BOSH#SEE_OTHER_HOST_KEY=Entry{scope=user, value=false}, CONNECTOR#STAGE_KEY=Entry{scope=stream, value=disconnecting}, RosterModule#ROSTER_STORE=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.modules.roster.DefaultRosterStore@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}}

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Connector state changed: disconnecting->disconnected

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=580 thread=3672] Stream terminated

2020-05-19 17:46:43 | 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;@7eec431e}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1960838c, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@cff123]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@387276f8}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@3fd14925}, 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@3c4df52e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@794f7cc3}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@63125ada}, 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 19 17:46:43 PDT 2020}}}}

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1d4977e7

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@73fb7ecd

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@7c34419d

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1883d134

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@4e237b8c

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: null->connecting

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] DNS entry stored in session object: localhost:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Preparing connection to [localhost:5222]

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connecting->connected

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Whitespace ping period is setted to nullms

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><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-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3677] Start TLS

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Proceeding TLS

2020-05-19 17:46:43 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Start handshake

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

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

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-19 17:46:43 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj1jR2pNZ1FoVEdycGtyWUpQS1o2RA==</auth>

2020-05-19 17:46:43 | MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jR2pNZ1FoVEdycGtyWUpQS1o2RFFLRkxSdnlCYXhKOFFYdE1TVTZ4LHM9VzQwUzB1YnZjRHVNdlE9PSxpPTQwOTY=</challenge>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Y0dqTWdRaFRHcnBrcllKUEtaNkRRS0ZMUnZ5QmF4SjhRWHRNU1U2eCxwPUx5UTZrODFoRmtGNVdLd3hrV1RUL0E0L2dwcG82cmZKVDlsWWdYY3lzcDA9</response>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1CK3FZN1RVVEQrOGRiZDlZSzZPRUVKVGdXMTJ6RitaZC9zbFYxVFJHZHE4PQ==</success>

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@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 xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="KwtM3r8q0j7Ak2s5rbvfU2u" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="KwtM3r8q0j7Ak2s5rbvfU2u" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-275</jid></bind></iq>

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq xmlns="jabber:client" id="yNg7nT5rbP5lJUYohT2eCxz" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <iq xmlns="jabber:client" id="yNg7nT5rbP5lJUYohT2eCxz" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"/>

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq to="localhost" id="nK5IO0bDzhN0eSX3fNR1vr3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

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

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <enabled xmlns="urn:xmpp:sm:3" id="93225a60-a070-45e5-abb9-6565c4cfdf30" location="9585556aacac" max="60" resume="true"/>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 >> <iq id="DGRphZ9l8qi5vgGwlbIAyu9" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@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-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <iq xmlns="jabber:client" id="DGRphZ9l8qi5vgGwlbIAyu9" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-275" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Connector state changed: connected->disconnecting

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=1] Terminating XMPP Stream

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-275 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-275"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Connector state changed: disconnecting->disconnected

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=581 thread=3679] Stream terminated

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=true

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: null->connecting

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] DNS entry stored in session object: localhost:5222

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Preparing connection to [localhost:5222]

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Connector state changed: connecting->connected

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=1] Whitespace ping period is setted to nullms

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><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-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3677] Start TLS

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Proceeding TLS

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Start handshake

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

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

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1NYU0tX3FqMm5ibzAyODEscj04YzdRZThuYlE4UFJTZVFYQ09zUQ==</auth>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj04YzdRZThuYlE4UFJTZVFYQ09zUUE5RHNzRURjeVBDUlFnSThuTThXLHM9VzQwUzB1YnZjRHVNdlE9PSxpPTQwOTY=</challenge>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OGM3UWU4bmJROFBSU2VRWENPc1FBOURzc0VEY3lQQ1JRZ0k4bk04VyxwPTNPTUZUZHQyRE5ON2JCNjAxZGJ6V2hSM3U3MlFlM05CaU5HSnN3NWdiekk9</response>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1rZkxPMk1JTlc4ZVFycnpTcmJUWlVEN2NTcndFazJnSkdZT2pkSDMzeXg0PQ==</success>

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@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 xmlns="http://jabber.org/protocol/caps" ver="2lH3Ks4CDBCGOGlwpMZ9qyWqd9g=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost >> <iq xmlns="jabber:client" id="zDGTV9zrl7pRauql1iKEBHK" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost << <iq xmlns="jabber:client" id="zDGTV9zrl7pRauql1iKEBHK" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>MaM-_qj2nbo0281@localhost/2095755800-tigase-276</jid></bind></iq>

2020-05-19 17:46:44 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq xmlns="jabber:client" id="eByWn5q0aDLSUcMJJLFzyk4" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="eByWn5q0aDLSUcMJJLFzyk4" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"/>

2020-05-19 17:46:44 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq to="localhost" id="QCzP9EsiY7vjLLW6Cr1bFBv" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <enabled xmlns="urn:xmpp:sm:3" id="0fba2aee-f689-45cc-ba05-ec474947bc3c" location="9585556aacac" max="60" resume="true"/>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="rEDV9r7Ews7nT5rbP5lJUYo" type="get"><query xmlns="jabber:iq:roster"/></iq>

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

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@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-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="rEDV9r7Ews7nT5rbP5lJUYo" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <presence xmlns="jabber:client" to="MaM-_qj2nbo0281@localhost" from="MaM-_qj2nbo0281@localhost/2095755800-tigase-276"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="uDyJSYmVZ7xlZrjNDIACJNQ" type="get"><auto xmlns="urn:xmpp:archive" save="true"/></iq>

2020-05-19 17:46:44 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="uDyJSYmVZ7xlZrjNDIACJNQ" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><auto xmlns="urn:xmpp:archive" save="true"/></iq>

2020-05-19 17:46:46 | [Mutex] received everything.

2020-05-19 17:46:46 | [Mutex] isItemNotified: setArchiveSettings:b0459601-636b-4323-9fbf-82ae877976ba:success :: true

2020-05-19 17:46:46 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 >> <iq id="xJOq3fDzp7HPPHQQ8LRY6m1" type="get"><retrieve xmlns="urn:xmpp:archive" with="MaM-_e7y2j10282@localhost"><set xmlns="http://jabber.org/protocol/rsm"/></retrieve></iq>

2020-05-19 17:46:46 | [Mutex] waiting for: [1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:count:0, 1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:received]

2020-05-19 17:46:46 | MaM- :: MaM-_qj2nbo0281@localhost/2095755800-tigase-276 << <iq xmlns="jabber:client" id="xJOq3fDzp7HPPHQQ8LRY6m1" to="MaM-_qj2nbo0281@localhost/2095755800-tigase-276" type="result"><chat xmlns="urn:xmpp:archive" with="MaM-_e7y2j10282@localhost"/></iq>

2020-05-19 17:46:46 | [Mutex] received everything.

2020-05-19 17:46:46 | [Mutex] isItemNotified: 1:2ee227f0-58cc-40f2-ae18-13dddfe818d4:b0459601-636b-4323-9fbf-82ae877976ba:retriveCollection:count:0 :: true

2020-05-19 17:46:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Connector state changed: connected->disconnected

2020-05-19 17:46:46 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=582 thread=3685] Stream terminated

2020-05-19 17:46:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-05-19 17:46:46 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

XEP-0136 - Message Archive - extended tests