Brute Force Prevention

2019-11-27 20:46:59 |

2019-11-27 20:46:59 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-27 20:46:59 | ------------------------------------

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@30ca4589

2019-11-27 20:46:59 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@3e0dade0}, 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@3a322ef9}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7f30a7db}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@1d3443b9}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=user}, 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=Wed Nov 27 20:46:59 PST 2019}}}}

2019-11-27 20:46:59 | [Mutex] waiting for: [registration]

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@4e059f52

2019-11-27 20:46:59 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@3e0dade0}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@3a322ef9}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7f30a7db}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@1d3443b9}, 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=user}, 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=Wed Nov 27 20:46:59 PST 2019}}}}

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@65eb258d

2019-11-27 20:46:59 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1f1f5894}

2019-11-27 20:46:59 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:46:59 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:46:59 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@2009e945

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@42140d19

2019-11-27 20:46:59 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@7eef8f9b}

2019-11-27 20:46:59 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:46:59 | user :: null >> <iq to="localhost" id="vsDslTPNU0sdHzBcO3ap3ra" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-27 20:46:59 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@abda2d0a}

2019-11-27 20:46:59 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="vsDslTPNU0sdHzBcO3ap3ra"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.Please also provide your e-mail address to which we will send confirmation link (MUST BE VALID!)</instructions><username/><password/><email/></query></iq>

2019-11-27 20:46:59 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@1b51a32a}

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@74fd22c4

2019-11-27 20:46:59 | user :: null >> <iq to="localhost" id="wD8Fx7n4k6vhJVQ6DkJHJKF" type="set"><query xmlns="jabber:iq:register"><username>user_9z0k580027</username><password>user_9z0k580027</password><email>f04fe5e1-8fb5-4e4c-837d-aad732af2821@localhost</email></query></iq>

2019-11-27 20:46:59 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@bd13fafd}

2019-11-27 20:46:59 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="wD8Fx7n4k6vhJVQ6DkJHJKF"/>

2019-11-27 20:46:59 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@16638587}

2019-11-27 20:46:59 | [Mutex] received everything.

2019-11-27 20:46:59 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-27 20:46:59 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1f1f5894, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@7eef8f9b]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@3e0dade0}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@3a322ef9}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7f30a7db}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@1d3443b9}, 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=user}, 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=Wed Nov 27 20:46:59 PST 2019}}}}

2019-11-27 20:46:59 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@1f1f5894, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@7eef8f9b]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@3e0dade0}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@3a322ef9}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7f30a7db}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@1d3443b9}, 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=user}, 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=Wed Nov 27 20:46:59 PST 2019}}}}

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@14f24387

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@22cfac0

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@1fda0343

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@159fe282

2019-11-27 20:46:59 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@13f765cd

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1JcEEySVdOYjhYcHBUSXBxcnloYw==</auth>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1JcEEySVdOYjhYcHBUSXBxcnloY1pQekR0T0VwbU44b2kxM2lZVkdILHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SXBBMklXTmI4WHBwVElwcXJ5aGNaUHpEdE9FcG1OOG9pMTNpWVZHSCxwPVhpMlZkc2NpeVlNcWxvQTQ0L3Z4c2JJeXgxcmJFd1lleVFVOWROcWVSNGc9</response>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1oanNVdmt3djkrbjFueWxjNjNUdmFrWklHUU56anZZNm1OaUxERXFrWjJ3PQ==</success>

2019-11-27 20:46:59 | user :: user_9z0k580027@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 hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <iq xmlns="jabber:client" id="uwLyk5nNJMKWbDrlGMZ29xp" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <iq to="user_9z0k580027@localhost/993927746-tigase-19" type="result" xmlns="jabber:client" id="uwLyk5nNJMKWbDrlGMZ29xp"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_9z0k580027@localhost/993927746-tigase-19</jid></bind></iq>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 >> <iq xmlns="jabber:client" id="yE7fDAmaDxfXUeTQLLR0bFy" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 << <iq to="user_9z0k580027@localhost/993927746-tigase-19" type="result" xmlns="jabber:client" id="yE7fDAmaDxfXUeTQLLR0bFy"/>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 >> <iq to="localhost" id="TzQOPWXmaqU2vdt5uaMRZ9L" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 >> <iq id="LxItbHGxnY3gJKzACkIEJFn" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="9c4be113-ea43-47e3-bf42-4f23922b9022"/>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 << <iq from="localhost" to="user_9z0k580027@localhost/993927746-tigase-19" type="result" xmlns="jabber:client" id="TzQOPWXmaqU2vdt5uaMRZ9L"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push: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>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 << <iq to="user_9z0k580027@localhost/993927746-tigase-19" type="result" xmlns="jabber:client" id="LxItbHGxnY3gJKzACkIEJFn"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:46:59 | Disconnecting

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost/993927746-tigase-19 << <presence to="user_9z0k580027@localhost" xmlns="jabber:client" from="user_9z0k580027@localhost/993927746-tigase-19"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 20:46:59 | [Mutex] notify: [disconnected]

2019-11-27 20:46:59 | [Mutex] received everything.

2019-11-27 20:46:59 | [Mutex] waiting for: [event]

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1EZ3pDeHR1UE5CSnJ0WHpFeHhHVg==</auth>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1EZ3pDeHR1UE5CSnJ0WHpFeHhHVmprcGxHZW91RER1bEJKUkg0d0d3LHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RGd6Q3h0dVBOQkpydFh6RXh4R1Zqa3BsR2VvdUREdWxCSlJINHdHdyxwPU5iTFV3aWRCL2dIeHVGcmZZaFg3NHlKNG4zRXMzcXRpdHdIR2wyc0RRUk09</response>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:46:59 | [Mutex] notify: [event, authFailed]

2019-11-27 20:46:59 | [Mutex] received everything.

2019-11-27 20:46:59 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:46:59 | [Mutex] notify: [event, disconnected]

2019-11-27 20:46:59 | [Mutex] waiting for: [event]

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:46:59 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1EdmRGRk8xWUxtZFZXWXRwS2hiUg==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1EdmRGRk8xWUxtZFZXWXRwS2hiUk42V0FEcVYwMVpIVTRpdzMwN0FuLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RHZkRkZPMVlMbWRWV1l0cEtoYlJONldBRHFWMDFaSFU0aXczMDdBbixwPWNsaDE4aCtobmxYUjBoT1JxbTJvcU4vczc0N1pURGVVUG5WN2laSW1Ka0U9</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj02Rm82WWREbG9FeHAwajJRZWRmYQ==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj02Rm82WWREbG9FeHAwajJRZWRmYXl4OHgyamp2WFhoN3lIa2ZpblNDLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NkZvNllkRGxvRXhwMGoyUWVkZmF5eDh4MmpqdlhYaDd5SGtmaW5TQyxwPU9sS1VzM1YwMXNZbHg4MmNnZkZEalcrdUFVaXNPVlB4NFlGdG9vM09mcjg9</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1FZ0hYQnRUeEFsa3JVcnFraFZ1SA==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1FZ0hYQnRUeEFsa3JVcnFraFZ1SDBNRlNiNXZ3Y0xBTGxuREJoN2s1LHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RWdIWEJ0VHhBbGtyVXJxa2hWdUgwTUZTYjV2d2NMQUxsbkRCaDdrNSxwPWZQTjVEam1NRDhpRDNKL1UzWGV1Z3V3TUZod1MvSGJUVVpHWFdaT0xVVms9</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1rUE1XNWZXU3NGcU1GYjlKOXpkeQ==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1rUE1XNWZXU3NGcU1GYjlKOXpkeUpUZk9YTkxNd1YxcGNpYW9TRlFFLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9a1BNVzVmV1NzRnFNRmI5Sjl6ZHlKVGZPWE5MTXdWMXBjaWFvU0ZRRSxwPWwwdGtqMWJCTkhvNlFtWlgycWxHa3JubW5TSWREQ29kWEFYbjQzTEFhc1U9</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1yVVQ1R2d5N2N6ZkhjOGxDVGViYQ==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1yVVQ1R2d5N2N6ZkhjOGxDVGViYUpUOEl5d0w5bXZCWjJMUnFkN05YLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9clVUNUdneTdjemZIYzhsQ1RlYmFKVDhJeXdMOW12QloyTFJxZDdOWCxwPTQrb0xMM2x4L3VHMkpMTEpmWTQwdDlVa1VZaFdRNk1Mblo3U2t2Y0FIbm89</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj03M1VNQmNqSUFSRXV4MVlUUmlFTQ==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03M1VNQmNqSUFSRXV4MVlUUmlFTTh0a0FINUxhWHFzR1VqZ1E0eVdVLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NzNVTUJjaklBUkV1eDFZVFJpRU04dGtBSDVMYVhxc0dVamdRNHlXVSxwPVhqZXFiWDBQSUxKNm9pVXpZdFQ1bFFreEZXVThYY01SUUE2dXlISWVHNk09</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1mOUdzTkVZZVNRa0tFcjB0d0k5MQ==</auth>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1mOUdzTkVZZVNRa0tFcjB0d0k5MU9YVlFlNkJZWERMclBQT0I4VWlrLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZjlHc05FWWVTUWtLRXIwdHdJOTFPWFZRZTZCWVhETHJQUE9COFVpayxwPVJhczhGdmNmVXFPZU9wZWhJNlJidnFSYVRmbFlmRitwdHQ2cjVwTnpIZk09</response>

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:00 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:00 | [Mutex] received everything.

2019-11-27 20:47:00 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:00 | [Mutex] waiting for: [event]

2019-11-27 20:47:00 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj03VlE2WTdHU1Y2N0JrYnh5UUdPSw==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03VlE2WTdHU1Y2N0JrYnh5UUdPSzFxMGVHRHg5YVhCM1RoVmFnWGQ4LHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9N1ZRNlk3R1NWNjdCa2J4eVFHT0sxcTBlR0R4OWFYQjNUaFZhZ1hkOCxwPXE0WVV6SHFBS1dmUnJtdWpFaVoyZk1WWWxjbG5zQ0JaUWxFN3BlcEFYUkE9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1yY05qSEFhcmNzTlRidUR5R3hoaQ==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1yY05qSEFhcmNzTlRidUR5R3hoaWQ5dVR5VUJGRjhTNWpDckFCVkRxLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cmNOakhBYXJjc05UYnVEeUd4aGlkOXVUeVVCRkY4UzVqQ3JBQlZEcSxwPU10U3JoMzFuL1gvSmVueFhjNFlacTgzMnFjZmxYTVA3N1BDbzBOVWJvd3M9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj00Q24wQUUzMjVKY0ZTaTJtSlZFUA==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj00Q24wQUUzMjVKY0ZTaTJtSlZFUGRKT25hS2ZidEx5WFhPSU1TejRhLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NENuMEFFMzI1SmNGU2kybUpWRVBkSk9uYUtmYnRMeVhYT0lNU3o0YSxwPXZiak1RQk1URHAxZ2I1d2lLUm5Hb3p5NlN6NXlBdWYycDNzbEdLRVUwTEE9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj14MXViMk5ybFNFd0ZaWXZQSWp0VA==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj14MXViMk5ybFNFd0ZaWXZQSWp0VFFBeHpvV1E5b2hENEEwUzR4SWpNLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eDF1YjJOcmxTRXdGWll2UElqdFRRQXh6b1dROW9oRDRBMFM0eElqTSxwPXh5bCtMTnUwYzE3S01mZ0U3T1ZoSlM0d0xoNjE5RGZXNWMyUWFpZW1sTTg9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj15TU5uTkp1SDJXQVl6VnVxemY0dA==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15TU5uTkp1SDJXQVl6VnVxemY0dFFuaXdnaGFENlpuZEpVRjZpT2NFLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eU1Obk5KdUgyV0FZelZ1cXpmNHRRbml3Z2hhRDZabmRKVUY2aU9jRSxwPTNNeFpyYzN0SEhVdG9sUFZnQldYQWx4dXRDZ253c3l2bUh4ZDJBTXBTMkk9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] notify: [event, disconnected]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1wQzA5WGVWYVdvTGFMbk1CeVMzWg==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1wQzA5WGVWYVdvTGFMbk1CeVMzWnF4dXNEMjliazU3ZllKcjNlT3JYLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cEMwOVhlVmFXb0xhTG5NQnlTM1pxeHVzRDI5Yms1N2ZZSnIzZU9yWCxwPXBzVTUyMThSTUFPLy9mLzJiOGFkdmpNVnNUWms4SVJpdWo3NWJZK3NuU1k9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] notify: [event, disconnected]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1FTHhuZXVCbTdDY0U4YzlMR3EzNQ==</auth>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1FTHhuZXVCbTdDY0U4YzlMR3EzNVJrNjJzMTVDVEkxTVYwdkxqOUVWLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RUx4bmV1Qm03Q2NFOGM5TEdxMzVSazYyczE1Q1RJMU1WMHZMajlFVixwPUhvLzVyYzdzbVJnNXQzVDRIcWg1WUZYVkphUlkreEt4SG1qenRtNlgvT2M9</response>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:01 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:01 | [Mutex] received everything.

2019-11-27 20:47:01 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:01 | [Mutex] waiting for: [event]

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:01 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1DRkFObzd6QU5jSExHSEZQSVFxbw==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1DRkFObzd6QU5jSExHSEZQSVFxb0czZ3RYaGRVV3FJZ2xTUFNHY0xCLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Q0ZBTm83ekFOY0hMR0hGUElRcW9HM2d0WGhkVVdxSWdsU1BTR2NMQixwPWFGdHN2Ym5kSnhTS0oxc3c4NkNZVTF0NC8xZkZySXFXYzMxZkJvaFRDbXc9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | [Mutex] waiting for: [event]

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj0wYXhzYnNlWjVjeXU5ZnFOSEFXeg==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0wYXhzYnNlWjVjeXU5ZnFOSEFXek9PSzVhY2hPQ2tkZWpTeGhma2xFLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MGF4c2JzZVo1Y3l1OWZxTkhBV3pPT0s1YWNoT0NrZGVqU3hoZmtsRSxwPURrUk1nSDdDQW5XZWxwOEJTdFZqN2phdjYzNGtOTWJEQlFvakVwYmpyRDA9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | [Mutex] waiting for: [event]

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1VOFptWFVzUklvUVVPNnVOamh0Zw==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1VOFptWFVzUklvUVVPNnVOamh0Z2ZaVXpIWnNtZUFzU3ZyaGNkaVVHLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VThabVhVc1JJb1FVTzZ1TmpodGdmWlV6SFpzbWVBc1N2cmhjZGlVRyxwPUlCbVdwdkJJVXpuS093UTh1MU1NVStENGZUY1ovZElPUkJLUms3elI2Tlk9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | [Mutex] waiting for: [event]

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj0zR0o0aW8wZ3BLYVNKMG81bGxVNw==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0zR0o0aW8wZ3BLYVNKMG81bGxVN1N0d2RhbTBCb3o3RkxtNDl6MTlzLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9M0dKNGlvMGdwS2FTSjBvNWxsVTdTdHdkYW0wQm96N0ZMbTQ5ejE5cyxwPVl4ZEE0L2s3REhxblFSNGRqaEgrS2szWXhBeWt5OHlUb3FFeFk3NWNaMFE9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | [Mutex] waiting for: [event]

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj15M1pqZzh6SmJnSWxwRnViZUFrUA==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15M1pqZzh6SmJnSWxwRnViZUFrUHlrUnhlWW15bkpSYXcwbDFlOGUxLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eTNaamc4ekpiZ0lscEZ1YmVBa1B5a1J4ZVlteW5KUmF3MGwxZThlMSxwPW5RQVp2b0NpRlhXd2xMMFY2TXE5WWhtQTJOZks2d0hMcCtTVUNJT0tBL3M9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | [Mutex] waiting for: [event]

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj10ZUxQR25EVnBLbmFmSXBNQjFEdQ==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj10ZUxQR25EVnBLbmFmSXBNQjFEdTlFTjlHRlZkaGFHdTRidEQzVTRoLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dGVMUEduRFZwS25hZklwTUIxRHU5RU45R0ZWZGhhR3U0YnREM1U0aCxwPUtCWWtZNmFlVzdFd3hxaVU4OWlFbmRTd05OZ2tEYk9JSEhrb2FyRCtwWFU9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:02 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:02 | [Mutex] received everything.

2019-11-27 20:47:02 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzl6MGs1ODAwMjcscj1udDB6REJQZFVzNDhTcG1jVzBOZw==</auth>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1udDB6REJQZFVzNDhTcG1jVzBOZ0pwQU00R2pZNFNabThFVjZIUm1NLHM9dVBTT1VKWHNkdWhGRmc9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bnQwekRCUGRVczQ4U3BtY1cwTmdKcEFNNEdqWTRTWm04RVY2SFJtTSxwPW9wV2tnSldqblZrTnN6UWttUEwwdUk5bVVlWm9za2lEOVN3WjlqZEp3b2M9</response>

2019-11-27 20:47:02 | user :: user_9z0k580027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><invalid-authzid/><text xml:lang="en">SCRAM: user_9z0k580027@localhost is not authorized to act as user_9z0k580027@localhost</text></failure>

2019-11-27 20:47:04 | [Mutex] waiting for: [user]

2019-11-27 20:47:04 | [Mutex] received everything.

2019-11-27 20:47:04 | [Mutex] isItemNotified: user:ok:false :: true

2019-11-27 20:47:04 |

2019-11-27 20:47:04 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-27 20:47:04 | ------------------------------------

2019-11-27 20:47:04 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@330f9f0

2019-11-27 20:47:04 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@df717e6}, 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@9c89280}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4cca1ddd}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@483192cd}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=user}, 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=Wed Nov 27 20:47:04 PST 2019}}}}

2019-11-27 20:47:04 | [Mutex] waiting for: [registration]

2019-11-27 20:47:04 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@7dccf40a

2019-11-27 20:47:04 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@df717e6}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@9c89280}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4cca1ddd}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@483192cd}, 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=user}, 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=Wed Nov 27 20:47:04 PST 2019}}}}

2019-11-27 20:47:04 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@30dd25a1

2019-11-27 20:47:04 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:04 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2ce5e292}

2019-11-27 20:47:04 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:04 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:04 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@6886aa39

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@72706f87

2019-11-27 20:47:05 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2bd9ac45}

2019-11-27 20:47:05 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:05 | user :: null >> <iq to="localhost" id="FNoVjCEJw7thQR1gDq3oRaM" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-27 20:47:05 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@efd4790b}

2019-11-27 20:47:05 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="FNoVjCEJw7thQR1gDq3oRaM"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.Please also provide your e-mail address to which we will send confirmation link (MUST BE VALID!)</instructions><username/><password/><email/></query></iq>

2019-11-27 20:47:05 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@28ab3ade}

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@75218a90

2019-11-27 20:47:05 | user :: null >> <iq to="localhost" id="6JaKNRTfuodu3l1kPX8nU1k" type="set"><query xmlns="jabber:iq:register"><username>user_f59owa0028</username><password>user_f59owa0028</password><email>622c3e63-541e-4d3d-baed-b58a95a2ee9a@localhost</email></query></iq>

2019-11-27 20:47:05 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@9a39fa9c}

2019-11-27 20:47:05 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="6JaKNRTfuodu3l1kPX8nU1k"/>

2019-11-27 20:47:05 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@45cdfe4}

2019-11-27 20:47:05 | [Mutex] received everything.

2019-11-27 20:47:05 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-27 20:47:05 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2ce5e292, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2bd9ac45]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@df717e6}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@9c89280}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4cca1ddd}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@483192cd}, 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=user}, 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=Wed Nov 27 20:47:05 PST 2019}}}}

2019-11-27 20:47:05 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@df717e6}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, 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@9c89280}, urn:xmpp:sm:3#SM_ACK_ENABLED=Entry{scope=stream, value=false}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4cca1ddd}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@483192cd}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=user}, socket#ServerHost=Entry{scope=user, value=localhost}, CONNECTOR#CONNECTOR_STAGE_TIMESTAMP_KEY=Entry{scope=stream, value=Wed Nov 27 20:47:05 PST 2019}}}}

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@6281e0ae

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@138d5bea

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@6a812601

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@8c0774a

2019-11-27 20:47:05 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@6529726c

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1HZFlDN1pycVB0NllXcFhCODBsOQ==</auth>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1HZFlDN1pycVB0NllXcFhCODBsOUcwdWVTbm5YZGZHcW5WRkx5cUlYLHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9R2RZQzdacnFQdDZZV3BYQjgwbDlHMHVlU25uWGRmR3FuVkZMeXFJWCxwPXJtMUQ2Qjd0aWJVR3lUSFhHWjM3WXRSSEpVVEprMG1od2VSbzByWHpZWEk9</response>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0xM2JLZ3FvOUUvQ1ZmSW9YSU1OUkp0RnNUUUlPQk1Ud2hwdEtQbmJqdVowPQ==</success>

2019-11-27 20:47:05 | user :: user_f59owa0028@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 hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <iq xmlns="jabber:client" id="oI5lY1uqWW6DtZ7yi2n1dTM" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <iq to="user_f59owa0028@localhost/993927746-tigase-20" type="result" xmlns="jabber:client" id="oI5lY1uqWW6DtZ7yi2n1dTM"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_f59owa0028@localhost/993927746-tigase-20</jid></bind></iq>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 >> <iq xmlns="jabber:client" id="SNiFubEncQMCslTPNU0sdHz" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 << <iq to="user_f59owa0028@localhost/993927746-tigase-20" type="result" xmlns="jabber:client" id="SNiFubEncQMCslTPNU0sdHz"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 >> <iq to="localhost" id="vEPQ7rVZiM0jTXnKUfybBxm" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="87a7d1ec-0392-4567-91a7-8a6f0674a9d0"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 >> <iq id="LFQJHJKFDu5kR05CGsYaLJv" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 << <iq from="localhost" to="user_f59owa0028@localhost/993927746-tigase-20" type="result" xmlns="jabber:client" id="vEPQ7rVZiM0jTXnKUfybBxm"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push: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>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 << <iq to="user_f59owa0028@localhost/993927746-tigase-20" type="result" xmlns="jabber:client" id="LFQJHJKFDu5kR05CGsYaLJv"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:05 | Disconnecting

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost/993927746-tigase-20 << <presence to="user_f59owa0028@localhost" xmlns="jabber:client" from="user_f59owa0028@localhost/993927746-tigase-20"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 20:47:05 | [Mutex] notify: [disconnected]

2019-11-27 20:47:05 | [Mutex] received everything.

2019-11-27 20:47:05 | [Mutex] waiting for: [event]

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1PUnpod0M2anFacHJiNUI2TFdkeg==</auth>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1PUnpod0M2anFacHJiNUI2TFdkek1PSTJDaDQzMXcwRHF6VHA5WjlSLHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9T1J6aHdDNmpxWnByYjVCNkxXZHpNT0kyQ2g0MzF3MERxelRwOVo5UixwPUJTTDdNR3RwWHZHODljSEdlQjduSFE0Q1NVQzJNb3hXanBvMnRITi9sVkk9</response>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:05 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:05 | [Mutex] received everything.

2019-11-27 20:47:05 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:05 | [Mutex] notify: [event, disconnected]

2019-11-27 20:47:05 | [Mutex] waiting for: [event]

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1IdlY5YnFKRG9IVW5KRnVCMWhmaA==</auth>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IdlY5YnFKRG9IVW5KRnVCMWhmaDd1MkZvTUNQRVg2ck52NHhqTjJGLHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SHZWOWJxSkRvSFVuSkZ1QjFoZmg3dTJGb01DUEVYNnJOdjR4ak4yRixwPWFqV1VLNmVMZ1NtSTlUdUhyeUJuM1VBQWlRK0dMMUxKRlE1bVhOeTlSV2s9</response>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:05 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:05 | [Mutex] notify: [event, disconnected]

2019-11-27 20:47:05 | [Mutex] received everything.

2019-11-27 20:47:05 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:05 | [Mutex] waiting for: [event]

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1VSGNXZ051Mm9RWXUydzN5OXlIRQ==</auth>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1VSGNXZ051Mm9RWXUydzN5OXlIRWZ0bmxZeUZ1cTBFRzRaTnhybUY0LHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VUhjV2dOdTJvUVl1MnczeTl5SEVmdG5sWXlGdXEwRUc0Wk54cm1GNCxwPURYSFFjTHZ5blVad0QyUytCbkZ1THRWcmNkN0hzZDZ3OExsRHlXenNYckE9</response>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:05 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:05 | [Mutex] received everything.

2019-11-27 20:47:05 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:05 | [Mutex] waiting for: [event]

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1oRjdrZUdnbmg0am9oTDRZUXVtcw==</auth>

2019-11-27 20:47:05 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1oRjdrZUdnbmg0am9oTDRZUXVtc3lXanpMQjJ6Zm5iUjh5MU9QV3hjLHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aEY3a2VHZ25oNGpvaEw0WVF1bXN5V2p6TEIyemZuYlI4eTFPUFd4YyxwPVpMU1V3cHpDWERnb3hubWE1Rll1eUdSUDBFV29xT1BDTU1VWkhwMjYxbVk9</response>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:06 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:06 | [Mutex] received everything.

2019-11-27 20:47:06 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2Y1OW93YTAwMjgscj1heXBlUll1VzBpSEdlWXJqcjRyUA==</auth>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1heXBlUll1VzBpSEdlWXJqcjRyUExnbENuTG1BYkxrY2Z1N2xoUTF2LHM9NGdTcG91N0JmTndxQlE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9YXlwZVJZdVcwaUhHZVlyanI0clBMZ2xDbkxtQWJMa2NmdTdsaFExdixwPWhrRnA5TDFORzduank5UFlkQVNic3puTThJMURnMXFLcHNSNXpXRmgweU09</response>

2019-11-27 20:47:06 | user :: user_f59owa0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:08 | [Mutex] waiting for: [user]

2019-11-27 20:47:08 | [Mutex] received everything.

2019-11-27 20:47:08 | [Mutex] isItemNotified: user:ok:true :: true

2019-11-27 20:47:08 |

2019-11-27 20:47:08 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-27 20:47:08 | ------------------------------------

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@6be8932a

2019-11-27 20:47:08 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@652f7027}, 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@6110f85a}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@24127a1c}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@bcccc77}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=user}, 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=Wed Nov 27 20:47:08 PST 2019}}}}

2019-11-27 20:47:08 | [Mutex] waiting for: [registration]

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@157c3b61

2019-11-27 20:47:08 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@652f7027}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@6110f85a}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@24127a1c}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@bcccc77}, 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=user}, 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=Wed Nov 27 20:47:08 PST 2019}}}}

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@7348d3bc

2019-11-27 20:47:08 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@443d8553}

2019-11-27 20:47:08 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:08 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:08 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@297f46d7

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@5dacd7aa

2019-11-27 20:47:08 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@684c3a90}

2019-11-27 20:47:08 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:08 | user :: null >> <iq to="localhost" id="4N8yi2n1dTMYnOHDBu9BqX4" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-27 20:47:08 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@aabcedb7}

2019-11-27 20:47:08 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="4N8yi2n1dTMYnOHDBu9BqX4"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.Please also provide your e-mail address to which we will send confirmation link (MUST BE VALID!)</instructions><username/><password/><email/></query></iq>

2019-11-27 20:47:08 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@40733dd7}

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@13e982f0

2019-11-27 20:47:08 | user :: null >> <iq to="localhost" id="NUrbEtfIxeFCj4AgAodDjLQ" type="set"><query xmlns="jabber:iq:register"><username>user_ewtsgh0029</username><password>user_ewtsgh0029</password><email>4b3906ea-d1d7-4b82-ab44-5b8f23bde948@localhost</email></query></iq>

2019-11-27 20:47:08 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@fb65595f}

2019-11-27 20:47:08 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="NUrbEtfIxeFCj4AgAodDjLQ"/>

2019-11-27 20:47:08 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@28c306a0}

2019-11-27 20:47:08 | [Mutex] received everything.

2019-11-27 20:47:08 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-27 20:47:08 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@443d8553, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@684c3a90]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@652f7027}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@6110f85a}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@24127a1c}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@bcccc77}, 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=user}, 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=Wed Nov 27 20:47:08 PST 2019}}}}

2019-11-27 20:47:08 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@443d8553, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@684c3a90]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@652f7027}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@6110f85a}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@24127a1c}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@bcccc77}, 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=user}, 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=Wed Nov 27 20:47:08 PST 2019}}}}

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@21523192

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@78163dea

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@2737ce1a

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@17712a5a

2019-11-27 20:47:08 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@279f5787

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2V3dHNnaDAwMjkscj1vQTcyRDZNaTcwNWcyTUl4dkwxQg==</auth>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1vQTcyRDZNaTcwNWcyTUl4dkwxQjlRd0d0YmhSUXpUUzY0RlRQNW5TLHM9Y0hhTi9KWUpYdGFGSVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9b0E3MkQ2TWk3MDVnMk1JeHZMMUI5UXdHdGJoUlF6VFM2NEZUUDVuUyxwPXVEMS81TDZsYy9SNVVJcnAzc0s0TlFPM2o0b3hiZmdBRFBJcTNlVk9KZ0E9</response>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1kR09oeElHa0NTYVJzSXNhZzVyZ1JOU0tGNXZWQ1UxTnNLTTlqb1MyTVZrPQ==</success>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@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 hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <iq xmlns="jabber:client" id="50Hyyp5l9i0rWX9DyhRWZ8t" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <iq to="user_ewtsgh0029@localhost/993927746-tigase-21" type="result" xmlns="jabber:client" id="50Hyyp5l9i0rWX9DyhRWZ8t"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_ewtsgh0029@localhost/993927746-tigase-21</jid></bind></iq>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 >> <iq xmlns="jabber:client" id="E0Fzj5aKVUT1n2fNOJCrapg" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 << <iq to="user_ewtsgh0029@localhost/993927746-tigase-21" type="result" xmlns="jabber:client" id="E0Fzj5aKVUT1n2fNOJCrapg"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 >> <iq to="localhost" id="hXuhQR1gDq3oRaMEmWjPHxo" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="1143d18a-b99a-45e7-83da-03e3f21d26f5"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 >> <iq id="pWp4dScu2i1fxdKGOV7phyw" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 << <iq from="localhost" to="user_ewtsgh0029@localhost/993927746-tigase-21" type="result" xmlns="jabber:client" id="hXuhQR1gDq3oRaMEmWjPHxo"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push: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>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 << <iq to="user_ewtsgh0029@localhost/993927746-tigase-21" type="result" xmlns="jabber:client" id="pWp4dScu2i1fxdKGOV7phyw"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:08 | Disconnecting

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost/993927746-tigase-21 << <presence to="user_ewtsgh0029@localhost" xmlns="jabber:client" from="user_ewtsgh0029@localhost/993927746-tigase-21"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 20:47:08 | [Mutex] notify: [disconnected]

2019-11-27 20:47:08 | [Mutex] received everything.

2019-11-27 20:47:08 | [Mutex] waiting for: [event]

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1">biwsbj11c2VyX2V3dHNnaDAwMjkscj13eXNWYkIzcWVxS3VRZlN3RGxIeQ==</auth>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj13eXNWYkIzcWVxS3VRZlN3RGxIeWdIVjJaOWdSVGZCRnVRamt4T05TLHM9YUdoNmdKanZHdVRxSkE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9d3lzVmJCM3FlcUt1UWZTd0RsSHlnSFYyWjlnUlRmQkZ1UWpreE9OUyxwPXlFN0h2LzgvQXdkeENJOXNRZlA1a3ZQSGQxbz0=</response>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:08 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:08 | [Mutex] received everything.

2019-11-27 20:47:08 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:08 | [Mutex] waiting for: [event]

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2V3dHNnaDAwMjkscj1kcUpja3ZUM2JCcm1RdTVFZWdUcQ==</auth>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1kcUpja3ZUM2JCcm1RdTVFZWdUcW9mNllPeUw4eWZTWDA5b3MzbzAxLHM9Y0hhTi9KWUpYdGFGSVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:08 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZHFKY2t2VDNiQnJtUXU1RWVnVHFvZjZZT3lMOHlmU1gwOW9zM28wMSxwPUEreTdRSEZBMmlLTkdOQXI1bXB4N0dYVkU0L0hIcVZnYityQ2VmZHNMeGc9</response>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:09 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:09 | [Mutex] received everything.

2019-11-27 20:47:09 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:09 | [Mutex] waiting for: [event]

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1">biwsbj11c2VyX2V3dHNnaDAwMjkscj1URDdiVmFKdmc0ck96bnBqTklOYQ==</auth>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1URDdiVmFKdmc0ck96bnBqTklOYXFZcVBOajVRZFE2NzlodW9mMGRaLHM9YUdoNmdKanZHdVRxSkE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VEQ3YlZhSnZnNHJPem5wak5JTmFxWXFQTmo1UWRRNjc5aHVvZjBkWixwPXBSb3ZiTHo0VXQwZEgvaUZ4RUdqb3VaVm1OUT0=</response>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:09 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:09 | [Mutex] received everything.

2019-11-27 20:47:09 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:09 | [Mutex] waiting for: [event]

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2V3dHNnaDAwMjkscj1Vak16b2J2NjBCRGZ4bTZuWXJUbw==</auth>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1Vak16b2J2NjBCRGZ4bTZuWXJUb2lOOGsyd2xrM1JPaEFNNjZwbXpULHM9Y0hhTi9KWUpYdGFGSVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VWpNem9idjYwQkRmeG02bllyVG9pTjhrMndsazNST2hBTTY2cG16VCxwPVB5b1B5dERwamg1S25pVys0Q2NDQk9ydWVreDduNktqOFdKTDR0VnV0clE9</response>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:09 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:09 | [Mutex] received everything.

2019-11-27 20:47:09 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2V3dHNnaDAwMjkscj1kbktDSGQzckYyM3JpQTFQa0h4dQ==</auth>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1kbktDSGQzckYyM3JpQTFQa0h4dUFIbUxNQjhNREg2WkRmWFN3NllSLHM9Y0hhTi9KWUpYdGFGSVE9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZG5LQ0hkM3JGMjNyaUExUGtIeHVBSG1MTUI4TURINlpEZlhTdzZZUixwPWVsYnJ6RFpvREtlUFQ4NTdYemo5YnlZZUV5TC9HSFNrcHlEZnpIOTJJdTg9</response>

2019-11-27 20:47:09 | user :: user_ewtsgh0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:11 | [Mutex] waiting for: [user]

2019-11-27 20:47:11 | [Mutex] received everything.

2019-11-27 20:47:11 | [Mutex] isItemNotified: user:ok:true :: true

2019-11-27 20:47:11 |

2019-11-27 20:47:11 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-27 20:47:11 | ------------------------------------

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@709e8a6e

2019-11-27 20:47:11 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@7e596a0d}, 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@7a66e11e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@3f646036}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@3bad504e}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=user}, 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=Wed Nov 27 20:47:11 PST 2019}}}}

2019-11-27 20:47:11 | [Mutex] waiting for: [registration]

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@e115c34

2019-11-27 20:47:11 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@7e596a0d}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@7a66e11e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@3f646036}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@3bad504e}, 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=user}, 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=Wed Nov 27 20:47:11 PST 2019}}}}

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@433232f9

2019-11-27 20:47:11 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5f4b9f79}

2019-11-27 20:47:11 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:11 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:11 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@e358bfe

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@ac196f0

2019-11-27 20:47:11 | user :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5836d8ab}

2019-11-27 20:47:11 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2019-11-27 20:47:11 | user :: null >> <iq to="localhost" id="QXmZcvaxmN2w8kSbzbvkQ8z" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-27 20:47:11 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@d4616438}

2019-11-27 20:47:11 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="QXmZcvaxmN2w8kSbzbvkQ8z"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.Please also provide your e-mail address to which we will send confirmation link (MUST BE VALID!)</instructions><username/><password/><email/></query></iq>

2019-11-27 20:47:11 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@5b052483}

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@794d3e66

2019-11-27 20:47:11 | user :: null >> <iq to="localhost" id="1e9tZ7yi2n1dTMYnOHDBu9B" type="set"><query xmlns="jabber:iq:register"><username>user_8x96740030</username><password>user_8x96740030</password><email>736819af-d29e-458f-aa43-b3db228e34a8@localhost</email></query></iq>

2019-11-27 20:47:11 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@65f4da11}

2019-11-27 20:47:11 | user :: null << <iq from="localhost" type="result" xmlns="jabber:client" id="1e9tZ7yi2n1dTMYnOHDBu9B"/>

2019-11-27 20:47:11 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@7fbd51a9}

2019-11-27 20:47:11 | [Mutex] received everything.

2019-11-27 20:47:11 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-27 20:47:11 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5f4b9f79, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5836d8ab]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@7e596a0d}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@7a66e11e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@3f646036}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@3bad504e}, 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=user}, 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=Wed Nov 27 20:47:11 PST 2019}}}}

2019-11-27 20:47:11 | 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;@266ffa07}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5f4b9f79, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@5836d8ab]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@7e596a0d}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@6752f03c}, 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@7a66e11e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@3f646036}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@3bad504e}, 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=user}, 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=Wed Nov 27 20:47:11 PST 2019}}}}

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@11203d75

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@637332ee

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@73dd9ee8

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@66f8a923

2019-11-27 20:47:11 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@76a3eb1c

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzh4OTY3NDAwMzAscj03U3hMdURWMm9zV1FwbWpUZXNrSw==</auth>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03U3hMdURWMm9zV1FwbWpUZXNrS0VmakhaODNSMzRWQmh0ZmJlUzlBLHM9Z085bUlyMk1nMnFWQ0E9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9N1N4THVEVjJvc1dRcG1qVGVza0tFZmpIWjgzUjM0VkJodGZiZVM5QSxwPUNnMXJhVFJVRlQweUxUQ0FHcmFCQWFUVjBLbzYxVC9jZXJPZmtyNjJrd009</response>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1aOS93RTRyNHNIL0lONWRPMCs4K0hYUVNxK2oydW5EZzBkT0dmNkN6OFRNPQ==</success>

2019-11-27 20:47:11 | user :: user_8x96740030@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 hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <iq xmlns="jabber:client" id="zR07yi2n1dTMYnOHDBu9BqX" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <iq to="user_8x96740030@localhost/993927746-tigase-22" type="result" xmlns="jabber:client" id="zR07yi2n1dTMYnOHDBu9BqX"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_8x96740030@localhost/993927746-tigase-22</jid></bind></iq>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 >> <iq xmlns="jabber:client" id="xgbx7n4k6vhJVQ6DkJHJKFD" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 << <iq to="user_8x96740030@localhost/993927746-tigase-22" type="result" xmlns="jabber:client" id="xgbx7n4k6vhJVQ6DkJHJKFD"/>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 >> <iq to="localhost" id="uWbo0r5dzs6EBt7HocPIvfP" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 >> <iq id="MW9vlY7xiHyjUTeUUSYnXX3" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 << <iq from="localhost" to="user_8x96740030@localhost/993927746-tigase-22" type="result" xmlns="jabber:client" id="uWbo0r5dzs6EBt7HocPIvfP"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push: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>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost/993927746-tigase-22 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="65dabdef-ac6b-49ea-967b-62456ba4df3b"/>

2019-11-27 20:47:11 | Disconnecting

2019-11-27 20:47:11 | [Mutex] notify: [disconnected]

2019-11-27 20:47:11 | [Mutex] received everything.

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <iq to="user_8x96740030@localhost/993927746-tigase-22" type="result" xmlns="jabber:client" id="MW9vlY7xiHyjUTeUUSYnXX3"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:11 | [Mutex] waiting for: [event]

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzh4OTY3NDAwMzAscj1WTWprY3FDb3ZQRVV4dzJMS05Heg==</auth>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1WTWprY3FDb3ZQRVV4dzJMS05HemdSVmtOdllWeVYyQ1IzNFhwUngwLHM9Z085bUlyMk1nMnFWQ0E9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Vk1qa2NxQ292UEVVeHcyTEtOR3pnUlZrTnZZVnlWMkNSMzRYcFJ4MCxwPUxIVTA4ejNGM09vdXZ5eDZUL2pZcUlZaEdwRDFSN2xuRGNwcTQrNTBlc2s9</response>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:11 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:11 | [Mutex] received everything.

2019-11-27 20:47:11 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:11 | [Mutex] waiting for: [event]

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:11 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzh4OTY3NDAwMzAscj1jM0cyNXBzd3lUVnlodVB2VDJ4aA==</auth>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1jM0cyNXBzd3lUVnlodVB2VDJ4aGxFS3BXMThJbHNNTThSUGtIZ1hXLHM9Z085bUlyMk1nMnFWQ0E9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9YzNHMjVwc3d5VFZ5aHVQdlQyeGhsRUtwVzE4SWxzTU04UlBrSGdYVyxwPWt6WDYwT0lNQzlVU3RUS2VlZnBQVS83YThTRWdoNzJwMmorWmFiUmFWWlU9</response>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:12 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:12 | [Mutex] received everything.

2019-11-27 20:47:12 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:12 | [Mutex] waiting for: [event]

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzh4OTY3NDAwMzAscj1sT3lvMENEb2RUTk1tQmdvMkQ4Yw==</auth>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1sT3lvMENEb2RUTk1tQmdvMkQ4YzdRbUMwMm5rczZ5ZVdzbUVtcEx1LHM9Z085bUlyMk1nMnFWQ0E9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bE95bzBDRG9kVE5NbUJnbzJEOGM3UW1DMDJua3M2eWVXc21FbXBMdSxwPUV6aHBpVC81UVJNRnhaTWUwUTU5cVNzT1pqcmFxOU44dFIvSkdsV2lnME09</response>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-27 20:47:12 | [Mutex] notify: [event, authFailed]

2019-11-27 20:47:12 | [Mutex] received everything.

2019-11-27 20:47:12 | [Mutex] isItemNotified: authFailed :: true

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyXzh4OTY3NDAwMzAscj1KSFpRVlYxWVdSSDBBbndIcXE3Nw==</auth>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1KSFpRVlYxWVdSSDBBbndIcXE3N1pxbGEzaW56SnFWT1ZLYlVLRkRaLHM9Z085bUlyMk1nMnFWQ0E9PSxpPTQwOTY=</challenge>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SkhaUVZWMVlXUkgwQW53SHFxNzdacWxhM2luekpxVk9WS2JVS0ZEWixwPVBRZkNDd3hKQm5ISXpJUEMxZm1ZYzc1OURZZVJqbjZWTjEvSkVWYmdpOUk9</response>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1IbWRCaVdFejJndmVZNURwaWVSUkZBTXY1dG1VK3YzV2hhVFl5YUZGdXE4PQ==</success>

2019-11-27 20:47:12 | user :: user_8x96740030@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 hash="SHA-1" node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="TbICgu8ra7zV0VOWQA2Ec+/yqKw="/></features>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost >> <iq xmlns="jabber:client" id="64vq9zj5aKVUT1n2fNOJCra" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost << <iq to="user_8x96740030@localhost/993927746-tigase-23" type="result" xmlns="jabber:client" id="64vq9zj5aKVUT1n2fNOJCra"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_8x96740030@localhost/993927746-tigase-23</jid></bind></iq>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 >> <iq xmlns="jabber:client" id="T0hGzqVi2ao0r5dzs6EBt7H" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 << <iq to="user_8x96740030@localhost/993927746-tigase-23" type="result" xmlns="jabber:client" id="T0hGzqVi2ao0r5dzs6EBt7H"/>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 >> <iq to="localhost" id="8dSZ9LwslKzn5s5tar2hQLY" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 >> <iq id="G6vrSaLAfJNSV9w9s7w7GOI" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 << <enabled resume="true" max="60" location="d4270859fb27" xmlns="urn:xmpp:sm:3" id="6d007fd3-3536-46c0-bd97-f6bf42ce241c"/>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 << <iq from="localhost" to="user_8x96740030@localhost/993927746-tigase-23" type="result" xmlns="jabber:client" id="8dSZ9LwslKzn5s5tar2hQLY"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10581/f55efd39" category="server"/><feature var="http://jabber.org/protocol/commands"/><x type="result" xmlns="jabber:x:data"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="jabber:iq:auth"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:ignore-unknown:0"/><feature var="tigase:push:muc:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push: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>

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 << <iq to="user_8x96740030@localhost/993927746-tigase-23" type="result" xmlns="jabber:client" id="G6vrSaLAfJNSV9w9s7w7GOI"><query xmlns="jabber:iq:roster"/></iq>

2019-11-27 20:47:12 | Disconnecting

2019-11-27 20:47:12 | user :: user_8x96740030@localhost/993927746-tigase-23 << <presence to="user_8x96740030@localhost" xmlns="jabber:client" from="user_8x96740030@localhost/993927746-tigase-23"><c hash="sha-1" node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2019-11-27 20:47:12 | [Mutex] notify: [disconnected]

2019-11-27 20:47:12 | [Mutex] received everything.

2019-11-27 20:47:14 | [Mutex] waiting for: [user]

2019-11-27 20:47:14 | [Mutex] received everything.

2019-11-27 20:47:14 | [Mutex] isItemNotified: user:ok:true :: true