Brute Force Prevention

2019-11-22 19:56:42 |

2019-11-22 19:56:42 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-22 19:56:42 | ------------------------------------

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@685d87e9

2019-11-22 19:56:42 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@669a6492}, 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@62a5ec99}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@27a76ea9}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@23c1c77b}, 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=Fri Nov 22 19:56:42 PST 2019}}}}

2019-11-22 19:56:42 | [Mutex] waiting for: [registration]

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@16ca8965

2019-11-22 19:56:42 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@669a6492}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@62a5ec99}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@27a76ea9}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@23c1c77b}, 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=Fri Nov 22 19:56:42 PST 2019}}}}

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@5b467e01

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

2019-11-22 19:56:42 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@478891e6}

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

2019-11-22 19:56:42 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-22 19:56:42 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@6a5f6c73

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@281c51fc

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

2019-11-22 19:56:42 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@40429488}

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

2019-11-22 19:56:42 | user :: null >> <iq to="localhost" id="XKv8u8t4jY3nbNHutZfXUfT" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-22 19:56:42 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@fbbfe0f8}

2019-11-22 19:56:42 | user :: null << <iq type="result" id="XKv8u8t4jY3nbNHutZfXUfT" xmlns="jabber:client" from="localhost"><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-22 19:56:42 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@43c62ac7}

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@52ebea38

2019-11-22 19:56:42 | user :: null >> <iq to="localhost" id="DwNENDxx4zxfEzmdrjKZn2q" type="set"><query xmlns="jabber:iq:register"><username>user_v87avh0027</username><password>user_v87avh0027</password><email>a6e7b792-58f8-48d5-bc1b-c856a0ba4521@localhost</email></query></iq>

2019-11-22 19:56:42 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@11a7990f}

2019-11-22 19:56:42 | user :: null << <iq type="result" id="DwNENDxx4zxfEzmdrjKZn2q" xmlns="jabber:client" from="localhost"/>

2019-11-22 19:56:42 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@18b2c228}

2019-11-22 19:56:42 | [Mutex] received everything.

2019-11-22 19:56:42 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-22 19:56:42 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@478891e6, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@40429488]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@669a6492}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@62a5ec99}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@27a76ea9}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@23c1c77b}, 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=Fri Nov 22 19:56:42 PST 2019}}}}

2019-11-22 19:56:42 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@478891e6, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@40429488]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@669a6492}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@62a5ec99}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@27a76ea9}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@23c1c77b}, 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=Fri Nov 22 19:56:42 PST 2019}}}}

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@4c80e362

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@3e626827

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@33d5fff9

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@3d78d829

2019-11-22 19:56:42 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@76a8b717

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

2019-11-22 19:56:42 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:42 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj0zdlB3RVRBbm1rU3VuUDhWckxmYQ==</auth>

2019-11-22 19:56:42 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0zdlB3RVRBbm1rU3VuUDhWckxmYVRlQ0VNNnNIaUpsRE1NRjhnZERLLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9M3ZQd0VUQW5ta1N1blA4VnJMZmFUZUNFTTZzSGlKbERNTUY4Z2RESyxwPW5Fdjlyck1uRTdoSWxCU0w0aGJWcW1pcmNMYXJMN1ozNDdMeEgwd3RlcDg9</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1KYXhIeE9VeGprN25iNTNEZ2d6V2lMbTBramdpSkw2TFlib01TejYwZHU4PQ==</success>

2019-11-22 19:56:43 | user :: user_v87avh0027@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 node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="vxsZDK3itTHNCC9pNOSKIQZW/14=" hash="SHA-1"/></features>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <iq xmlns="jabber:client" id="wyRR4fLXlNGRLN36DFvaBql" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <iq type="result" to="user_v87avh0027@localhost/993927746-tigase-19" id="wyRR4fLXlNGRLN36DFvaBql" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_v87avh0027@localhost/993927746-tigase-19</jid></bind></iq>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 >> <iq xmlns="jabber:client" id="TzSdPWeCs4lONWZfVhFqlJJ" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 << <iq type="result" to="user_v87avh0027@localhost/993927746-tigase-19" id="TzSdPWeCs4lONWZfVhFqlJJ" xmlns="jabber:client"/>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 >> <iq to="localhost" id="QX0ZpWn9AwcCoZ6n6jTdRPK" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 << <enabled resume="true" id="a55cd3f3-09d2-46e9-aaec-1978f3a2fd5f" xmlns="urn:xmpp:sm:3" max="60" location="d4270859fb27"/>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 >> <iq id="6UPJyBfLZYn9t6taPFLENZg" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:43 | user :: user_v87avh0027@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-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 << <iq type="result" to="user_v87avh0027@localhost/993927746-tigase-19" id="QX0ZpWn9AwcCoZ6n6jTdRPK" xmlns="jabber:client" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push: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-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 << <iq type="result" to="user_v87avh0027@localhost/993927746-tigase-19" id="6UPJyBfLZYn9t6taPFLENZg" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:43 | Disconnecting

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost/993927746-tigase-19 << <presence to="user_v87avh0027@localhost" xmlns="jabber:client" from="user_v87avh0027@localhost/993927746-tigase-19"><c node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-22 19:56:43 | [Mutex] notify: [disconnected]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1yVnZ2TktTcTZ0TjFwdGdpWTVtZQ==</auth>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1yVnZ2TktTcTZ0TjFwdGdpWTVtZURkNnNUYUxJVjg1dlltQktFaHVxLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9clZ2dk5LU3E2dE4xcHRnaVk1bWVEZDZzVGFMSVY4NXZZbUJLRWh1cSxwPW83andCOXpuZGNvL0F4eUhXRHJ1ODJ1a1FLbHhtZUNFNzRCRmZGbWlIWG89</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:43 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1UQUlqRW1FUk9CU0wydU1GcjR6UQ==</auth>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1UQUlqRW1FUk9CU0wydU1GcjR6UWhLSmJNcUhqY1VtMjJTc0VPdkRTLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VEFJakVtRVJPQlNMMnVNRnI0elFoS0piTXFIamNVbTIyU3NFT3ZEUyxwPWRiaXZHTUJFWWlVcHA1UlN4VVFNTkJzN3pDNjBnV1N1ZUVZbVNGTWtndHc9</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:43 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1yZERLc2FUbEZXcncxNTZHMkxoeA==</auth>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1yZERLc2FUbEZXcncxNTZHMkxoeDdyRWdOeFFpTEJZV2JUeDYyNTZyLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cmRES3NhVGxGV3J3MTU2RzJMaHg3ckVnTnhRaUxCWVdiVHg2MjU2cixwPUg5RVEvdm1zVVM4WDRwTzRBdWJZVTBKSXJyb2NLdDN0NGpraWhtdEFYVVU9</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:43 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:43 | [Mutex] notify: [event, disconnected]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1WTzZ3c2EwMFZRWHZLallOSUJ6OA==</auth>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1WTzZ3c2EwMFZRWHZLallOSUJ6OEgxb0VCWXUxZlpuOFh1UHY5TTlJLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Vk82d3NhMDBWUVh2S2pZTklCejhIMW9FQll1MWZabjhYdVB2OU05SSxwPTI3MFRRZVhQQ0Jmd2JUZDRROHlIaVBPYTludVpwS2VQamRuNENHUWs0Mnc9</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:43 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1KTkZwZ280VDlsbjZVMjE4bmxEcA==</auth>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1KTkZwZ280VDlsbjZVMjE4bmxEcHphdWZOeEk4N0puN1Nyb0VDSlk5LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Sk5GcGdvNFQ5bG42VTIxOG5sRHB6YXVmTnhJODdKbjdTcm9FQ0pZOSxwPWFTWDVTSm5qTXJKSWdTMmRkUmRFN3hmaHVaNGwwMksxRGhaSGwyQzVHeXc9</response>

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:43 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:43 | [Mutex] received everything.

2019-11-22 19:56:43 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:43 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:43 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj15UDNTVFNiWDdTUUsyaWw4Q2l2QQ==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj15UDNTVFNiWDdTUUsyaWw4Q2l2QWZUV2tLYjZqb1NiMVVxcWVqalJtLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9eVAzU1RTYlg3U1FLMmlsOENpdkFmVFdrS2I2am9TYjFVcXFlampSbSxwPWdkMHZLamVqTHl2bDNJaUp3WWwzWStadVNnMUdHZ3dXRVBoOXRBT2RZRDQ9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1DNUpsQWhKWFluWjRUMFpRb25qcQ==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1DNUpsQWhKWFluWjRUMFpRb25qcWJ4V2NuckxpZ1E2UkdVMHhrSGhuLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QzVKbEFoSlhZblo0VDBaUW9uanFieFdjbnJMaWdRNlJHVTB4a0hobixwPWRZa3ZSVFMrN0lqRDRrMzNJTUJNR1poYktqTE5BNHMwOWxSbnVRVDF4Q3c9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj0xVTVPNmdKSFBvTXpMZTAzckxnYQ==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0xVTVPNmdKSFBvTXpMZTAzckxnYWZoOFRWekNxM2VHNTBOWHZ4Y3BGLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MVU1TzZnSkhQb016TGUwM3JMZ2FmaDhUVnpDcTNlRzUwTlh2eGNwRixwPXpoTTZWdjBGVzllWktlcnpsWG1iQW56dGd1azlKTVg3dlJTdjNhdVBFMUk9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1nRW92RDlWTUtMV0hjd1NkSVdpUw==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1nRW92RDlWTUtMV0hjd1NkSVdpU2FPQVN0NDNYaDFIdllhMWM1RUNqLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Z0VvdkQ5Vk1LTFdIY3dTZElXaVNhT0FTdDQzWGgxSHZZYTFjNUVDaixwPXN1VmZSMTRGY0I4RmtnL3RSZ0xlRzkxWHRpS0VSR3hVVzNlU3J6NURKOUE9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] notify: [event, disconnected]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1JT0lJS2JFSjhHZnBuVmFPUzZIbQ==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1JT0lJS2JFSjhHZnBuVmFPUzZIbTFGdHRvODhienVqaTVwalVaeVdQLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SU9JSUtiRUo4R2ZwblZhT1M2SG0xRnR0bzg4Ynp1amk1cGpVWnlXUCxwPU5KaTk0SEVUdzBadEI1bU0xRjdPSk1iWDBUSVJXbjZIRnJLeTRoTWdSa1U9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj11ZUppWkM4cjByNjhKNVFSTnp6OQ==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj11ZUppWkM4cjByNjhKNVFSTnp6OTB4ODhuNlpQbmNCSzdyaVFTOGU1LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dWVKaVpDOHIwcjY4SjVRUk56ejkweDg4bjZaUG5jQks3cmlRUzhlNSxwPS9wQ1gwdkVmSHN5dDZPNjhrZ3d0WE9PaUJnSkdGdUVnVnBNcHRNYStkSWs9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj05ekdyTjVLS1NnckNvd3k0R3BOaw==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj05ekdyTjVLS1NnckNvd3k0R3BOazkxUmIwSDRISFlZMEJ3SFpuckRpLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OXpHck41S0tTZ3JDb3d5NEdwTms5MVJiMEg0SEhZWTBCd0habnJEaSxwPTdQS0xQRElGNk14UGI0V3R5SjdqTUZNbGF4RWtDb1N4L1hQMnNGcWNPT2c9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj10SnlJSk9NakRrdXZBZEltWUFTTg==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj10SnlJSk9NakRrdXZBZEltWUFTTms1Zk9YTnppcUhoWm9CWjluckF5LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dEp5SUpPTWpEa3V2QWRJbVlBU05rNWZPWE56aXFIaFpvQlo5bnJBeSxwPW9yeHRzN0pCOG1nUFJRVzE0WmV2QWhmaVlmTFNPejdSc256VFFDL1hsY1U9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:44 | [Mutex] notify: [event, disconnected]

2019-11-22 19:56:44 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1yaUFpaFZQbzROZUJOT2dOTGlDaA==</auth>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1yaUFpaFZQbzROZUJOT2dOTGlDaGZKSEl1Qm44M1pDUkxRV1AwZnFJLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cmlBaWhWUG80TmVCTk9nTkxpQ2hmSkhJdUJuODNaQ1JMUVdQMGZxSSxwPTQvSGx6WEpCc3ArWjY4bnhoTnMza09pRGsyaTFEZ05ZV2dDMzUwTnFsVnM9</response>

2019-11-22 19:56:44 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:44 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:44 | [Mutex] received everything.

2019-11-22 19:56:44 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj04czdMOVo5bWtWN2hMNWlIb2xnYg==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj04czdMOVo5bWtWN2hMNWlIb2xnYkFqdkZMWVBxblRGTDQ1TzJ0eGF6LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9OHM3TDlaOW1rVjdoTDVpSG9sZ2JBanZGTFlQcW5URkw0NU8ydHhheixwPWNIUUhaYVh0QlJYMDlNMng4ME9iZGgrWG5kQ1MyNVBERkZ6MThtMTdVVms9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj0wVEo4U1hqT0xXTDhFVkVkM1ptUg==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0wVEo4U1hqT0xXTDhFVkVkM1ptUkE1Q3ZuV3IyV3RQT2Y1VkJoN2toLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MFRKOFNYak9MV0w4RVZFZDNabVJBNUN2bldyMld0UE9mNVZCaDdraCxwPTNUOW1tdHRJQUE0N1lwMUMreFZKQnZCcXlPZXJXRGx4TTB3YjVuQjFxT0E9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1Sb3JBSHJHQVRxbklEVzI3QlJ6Rw==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1Sb3JBSHJHQVRxbklEVzI3QlJ6R0RGOHJ5TWdveG94NzJPRFRlUG9iLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Um9yQUhyR0FUcW5JRFcyN0JSekdERjhyeU1nb3hveDcyT0RUZVBvYixwPWlKa1Rmd2J4azZ2aG5tTTZ1VnVtYW0vRCs4RHNLOStqYWJYb1NiVmZVeDQ9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj0zZEJFcUQ1R1VvbnVURlFWUFM3WQ==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0zZEJFcUQ1R1VvbnVURlFWUFM3WTMwNU1CYlRJNUplZXZRVVVvTDN4LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9M2RCRXFENUdVb251VEZRVlBTN1kzMDVNQmJUSTVKZWV2UVVVb0wzeCxwPWExTG5mN1drM200bDhjd2xDLzdERE4ra01XU0FMZ2RoZjdML3VwUWZmNFU9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1pZ3dJSGlHWWx3bzhHUm9Ed2FFWQ==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1pZ3dJSGlHWWx3bzhHUm9Ed2FFWTVGQ0M2cjNIUkJuZGZZZjB0d040LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9aWd3SUhpR1lsd284R1JvRHdhRVk1RkNDNnIzSFJCbmRmWWYwdHdONCxwPVNXNFVYcG1uSlFxR00xQjZXLy9tL29KQnJ2N2JiNTE0UCtBT2xmanF6Q3M9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1CTUZKVzRlMFNmVFJSQnVRUHZaQQ==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CTUZKVzRlMFNmVFJSQnVRUHZaQVNEcG9QMnFaZmphT2M5VjJDekZPLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Qk1GSlc0ZTBTZlRSUkJ1UVB2WkFTRHBvUDJxWmZqYU9jOVYyQ3pGTyxwPUxtNmFrc3gvZ0d3cXV3YVRhZkhHNG94K1g3TzNKWDRlRUgyRzJYZGtQQmM9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:45 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj1DTndFdFF5cFRxaFFMU3FESjV1Ug==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1DTndFdFF5cFRxaFFMU3FESjV1UlEzS1h4S2tvdmxEa1VCRmtYQjJ4LHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Q053RXRReXBUcWhRTFNxREo1dVJRM0tYeEtrb3ZsRGtVQkZrWEIyeCxwPUltdnhxV1lxT0dXaVQrK2Z0TFowbzdQdERqMElqMlR6cEtKblRUaEpaeVE9</response>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:45 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:45 | [Mutex] received everything.

2019-11-22 19:56:45 | [Mutex] isItemNotified: authFailed :: true

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3Y4N2F2aDAwMjcscj01M3JIRHVXaThDaVAwb0N1M1QwSw==</auth>

2019-11-22 19:56:45 | user :: user_v87avh0027@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj01M3JIRHVXaThDaVAwb0N1M1QwS0dWa2RxdzhCcExnelJSb1llWFJZLHM9NnJ5N0VXTVBJUmhZdUE9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:46 | user :: user_v87avh0027@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NTNySER1V2k4Q2lQMG9DdTNUMEtHVmtkcXc4QnBMZ3pSUm9ZZVhSWSxwPTBXaUlvU1pTbDRyb1M0OUw2eEdtcjdDMEFWOEZQV2RHaFBZR3NDbjdiK2M9</response>

2019-11-22 19:56:46 | user :: user_v87avh0027@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><invalid-authzid/><text xml:lang="en">SCRAM: user_v87avh0027@localhost is not authorized to act as user_v87avh0027@localhost</text></failure>

2019-11-22 19:56:48 | [Mutex] waiting for: [user]

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] isItemNotified: user:ok:false :: true

2019-11-22 19:56:48 |

2019-11-22 19:56:48 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-22 19:56:48 | ------------------------------------

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@12d5df7f

2019-11-22 19:56:48 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@1c123355}, 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@182db40f}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@5d2f396e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@613d3b93}, 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=Fri Nov 22 19:56:48 PST 2019}}}}

2019-11-22 19:56:48 | [Mutex] waiting for: [registration]

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@6c38247a

2019-11-22 19:56:48 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@1c123355}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@182db40f}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@5d2f396e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@613d3b93}, 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=Fri Nov 22 19:56:48 PST 2019}}}}

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@19c0783e

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

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

2019-11-22 19:56:48 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@3d00c621}

2019-11-22 19:56:48 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-22 19:56:48 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@356993b1

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@185f7a92

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

2019-11-22 19:56:48 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2c4f02f}

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

2019-11-22 19:56:48 | user :: null >> <iq to="localhost" id="bNodqhX5kV4DhZ6HNS6mW2h" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-22 19:56:48 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@c6ec446b}

2019-11-22 19:56:48 | user :: null << <iq type="result" id="bNodqhX5kV4DhZ6HNS6mW2h" xmlns="jabber:client" from="localhost"><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-22 19:56:48 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@394e1f98}

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@40c3f71b

2019-11-22 19:56:48 | user :: null >> <iq to="localhost" id="6YTRO7hP7eTbxeJGRJTUj1k" type="set"><query xmlns="jabber:iq:register"><username>user_wuphmy0028</username><password>user_wuphmy0028</password><email>2077723b-4af0-407f-9bf9-78834ca675a5@localhost</email></query></iq>

2019-11-22 19:56:48 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@a62f35ac}

2019-11-22 19:56:48 | user :: null << <iq type="result" id="6YTRO7hP7eTbxeJGRJTUj1k" xmlns="jabber:client" from="localhost"/>

2019-11-22 19:56:48 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@23ab9e7b}

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-22 19:56:48 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@3d00c621, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2c4f02f]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@1c123355}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@182db40f}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@5d2f396e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@613d3b93}, 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=Fri Nov 22 19:56:48 PST 2019}}}}

2019-11-22 19:56:48 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@3d00c621, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@2c4f02f]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@1c123355}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@182db40f}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@5d2f396e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@613d3b93}, 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=Fri Nov 22 19:56:48 PST 2019}}}}

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@64b47a9

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@4fb02a27

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@109477e5

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@6f336bca

2019-11-22 19:56:48 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@33190f8a

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj0wUFkwaVNBWk9kT2drWTg2N1pPbA==</auth>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0wUFkwaVNBWk9kT2drWTg2N1pPbEUyRHBVRlN1WnFKWTVVbVluRlU2LHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MFBZMGlTQVpPZE9na1k4NjdaT2xFMkRwVUZTdVpxSlk1VW1ZbkZVNixwPXR0UzlYbFJ4UUdXa2doTmd6TnRGcVJaOERXMlZ6cFA5b2RPKzkzOUoxWnM9</response>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Dc0puUGVURjk3L2VrRGZ3bWVoNFZ0UGYzL01VWUN3SFVtVmo4M3lnYVRNPQ==</success>

2019-11-22 19:56:48 | user :: user_wuphmy0028@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 node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="vxsZDK3itTHNCC9pNOSKIQZW/14=" hash="SHA-1"/></features>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <iq xmlns="jabber:client" id="U36zqcykOPMJOJJTdxaPHFN" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <iq type="result" to="user_wuphmy0028@localhost/993927746-tigase-20" id="U36zqcykOPMJOJJTdxaPHFN" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_wuphmy0028@localhost/993927746-tigase-20</jid></bind></iq>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 >> <iq xmlns="jabber:client" id="k47dRQbLOY6q0dyhUV7FLDm" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 << <iq type="result" to="user_wuphmy0028@localhost/993927746-tigase-20" id="k47dRQbLOY6q0dyhUV7FLDm" xmlns="jabber:client"/>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 >> <iq to="localhost" id="K1WfFufIVR9ql5sgGAkRQR4" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 >> <iq id="B2XjJyBfLZYn9t6taPFLENZ" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 << <enabled resume="true" id="9d01c5e3-9831-4566-8274-f97d9adc45ee" xmlns="urn:xmpp:sm:3" max="60" location="d4270859fb27"/>

2019-11-22 19:56:48 | user :: user_wuphmy0028@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-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 << <iq type="result" to="user_wuphmy0028@localhost/993927746-tigase-20" id="K1WfFufIVR9ql5sgGAkRQR4" xmlns="jabber:client" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push: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-22 19:56:48 | user :: user_wuphmy0028@localhost/993927746-tigase-20 << <iq type="result" to="user_wuphmy0028@localhost/993927746-tigase-20" id="B2XjJyBfLZYn9t6taPFLENZ" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:48 | Disconnecting

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

2019-11-22 19:56:48 | [Mutex] notify: [disconnected]

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj1IdkhkQjBVMElQUk83NWhkNVltUw==</auth>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1IdkhkQjBVMElQUk83NWhkNVltU2lLODB5MXFnVkV1d0NCOExqNlh2LHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9SHZIZEIwVTBJUFJPNzVoZDVZbVNpSzgweTFxZ1ZFdXdDQjhMajZYdixwPU9zRkVHeFVWSDRjM0dCRzdWSUkzNVFSTC9hN2RYMW9RaXUybmo3WWZHY0E9</response>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:48 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:48 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj1MRG82R2tPTzdDQjNUZ3Jzbmt2TA==</auth>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1MRG82R2tPTzdDQjNUZ3Jzbmt2TEltNnR1QWYzUGxLNHk2d291TzVuLHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TERvNkdrT083Q0IzVGdyc25rdkxJbTZ0dUFmM1BsSzR5NndvdU81bixwPUxCM3FxcGRiZXgyTzBSVE44UG50bEM1NkFPRXJqK3JZZ2tzYXZqV2tUU3c9</response>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:48 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:48 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj0yUmowR3RUM3ZXTVJROE9NQlpDRg==</auth>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0yUmowR3RUM3ZXTVJROE9NQlpDRlpwcVF3R3R2NlB1OGVtOHZsSjJuLHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MlJqMEd0VDN2V01SUThPTUJaQ0ZacHFRd0d0djZQdThlbTh2bEoybixwPXhxTnhmaGpKZHVoK1FEbGhqNDdsY3diRTZCODNQTjIrNVkzNlJNK29SSHc9</response>

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:48 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:48 | [Mutex] received everything.

2019-11-22 19:56:48 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:48 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:48 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj12WDBDbHEyVFlQWnVIRFEwM3FCeQ==</auth>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj12WDBDbHEyVFlQWnVIRFEwM3FCeXVvOXc2Tmp4TWFnU1Bvb1NzMUh1LHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9dlgwQ2xxMlRZUFp1SERRMDNxQnl1bzl3Nk5qeE1hZ1NQb29TczFIdSxwPTJzbGRoQlhzODlZZ0swcEdTaE1JN2xJMlJwdEVVUTVJOGZGY044Kzd5d1k9</response>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:49 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:49 | [Mutex] notify: [event, disconnected]

2019-11-22 19:56:49 | [Mutex] received everything.

2019-11-22 19:56:49 | [Mutex] isItemNotified: authFailed :: true

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

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX3d1cGhteTAwMjgscj03aEVnSmhISW4xdzh6VXhPOWNuUQ==</auth>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj03aEVnSmhISW4xdzh6VXhPOWNuUWpLZHBkaXhxN0pzQ2Y1dVJPUTlYLHM9WEdXclcvZlZvejVIWmc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9N2hFZ0poSEluMXc4elV4TzljblFqS2RwZGl4cTdKc0NmNXVST1E5WCxwPXptb3R6VlQ3RHl6aXlRUHRydGd1VDlsQmdKVTNHQ3QwbTBsUFZ1Y0I1MVE9</response>

2019-11-22 19:56:49 | user :: user_wuphmy0028@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:51 | [Mutex] waiting for: [user]

2019-11-22 19:56:51 | [Mutex] received everything.

2019-11-22 19:56:51 | [Mutex] isItemNotified: user:ok:true :: true

2019-11-22 19:56:51 |

2019-11-22 19:56:51 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-22 19:56:51 | ------------------------------------

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@3d8fb680

2019-11-22 19:56:51 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@33484f41}, 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@3777ddf0}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7275457a}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@320ed602}, 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=Fri Nov 22 19:56:51 PST 2019}}}}

2019-11-22 19:56:51 | [Mutex] waiting for: [registration]

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@43cdb545

2019-11-22 19:56:51 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@33484f41}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@3777ddf0}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7275457a}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@320ed602}, 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=Fri Nov 22 19:56:51 PST 2019}}}}

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@4a5c7891

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

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

2019-11-22 19:56:51 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@125aba35}

2019-11-22 19:56:51 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-22 19:56:51 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@5620c0ba

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@786920a6

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

2019-11-22 19:56:51 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@515857ca}

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

2019-11-22 19:56:51 | user :: null >> <iq to="localhost" id="0M5jY3nbNHutZfXUfTX4p2z" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-22 19:56:51 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@cc6a44dc}

2019-11-22 19:56:51 | user :: null << <iq type="result" id="0M5jY3nbNHutZfXUfTX4p2z" xmlns="jabber:client" from="localhost"><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-22 19:56:51 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@1614c4c6}

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@46e1e3ae

2019-11-22 19:56:51 | user :: null >> <iq to="localhost" id="p4V6ygKEv8rezpfVX0v3ndq" type="set"><query xmlns="jabber:iq:register"><username>user_lpqskc0029</username><password>user_lpqskc0029</password><email>0a36cbea-aeb8-4196-8902-2be21fca90c3@localhost</email></query></iq>

2019-11-22 19:56:51 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@420bdf36}

2019-11-22 19:56:51 | user :: null << <iq type="result" id="p4V6ygKEv8rezpfVX0v3ndq" xmlns="jabber:client" from="localhost"/>

2019-11-22 19:56:51 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@773c97c4}

2019-11-22 19:56:51 | [Mutex] received everything.

2019-11-22 19:56:51 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-22 19:56:51 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@125aba35, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@515857ca]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@33484f41}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@3777ddf0}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7275457a}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@320ed602}, 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=Fri Nov 22 19:56:51 PST 2019}}}}

2019-11-22 19:56:51 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@125aba35, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@515857ca]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@33484f41}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@3777ddf0}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7275457a}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@320ed602}, 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=Fri Nov 22 19:56:51 PST 2019}}}}

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@644968ee

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@544ba255

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@1dfec7e1

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@e6ff12d

2019-11-22 19:56:51 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@214ee616

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2xwcXNrYzAwMjkscj1vMUlVV2VCUFV0QVJ4ZVRPSVJaUw==</auth>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1vMUlVV2VCUFV0QVJ4ZVRPSVJaU01GZHNBSTdQN08xeW1ZV3A2RWUxLHM9YkJoTGFiWXpyaEpNd0E9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9bzFJVVdlQlBVdEFSeGVUT0lSWlNNRmRzQUk3UDdPMXltWVdwNkVlMSxwPXlmblhKMnMyZ0hJSGRtRUdmWWdKdForVFZnQ1ljY2R2N0d0eWdySmxGckU9</response>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1sSU1hazNYWVFmZE1qelVUUUI2U1RZbWRVVUNIK0tPV0JwRXI2eWc5a0FnPQ==</success>

2019-11-22 19:56:51 | user :: user_lpqskc0029@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 node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="vxsZDK3itTHNCC9pNOSKIQZW/14=" hash="SHA-1"/></features>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <iq xmlns="jabber:client" id="c98eTbxeJGRJTUj1k0aygJA" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <iq type="result" to="user_lpqskc0029@localhost/993927746-tigase-21" id="c98eTbxeJGRJTUj1k0aygJA" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_lpqskc0029@localhost/993927746-tigase-21</jid></bind></iq>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 >> <iq xmlns="jabber:client" id="wP8par9JTTSfxl9i1r3gNO7" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 << <iq type="result" to="user_lpqskc0029@localhost/993927746-tigase-21" id="wP8par9JTTSfxl9i1r3gNO7" xmlns="jabber:client"/>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 >> <iq to="localhost" id="jWrlHPM3viW2iKHwdMFBDHv" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 >> <iq id="p8ZeOMMIDoXgDxvrZ4DjTfu" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 << <enabled resume="true" id="ef65c853-b4f0-4548-86fe-cf0bd23f2588" xmlns="urn:xmpp:sm:3" max="60" location="d4270859fb27"/>

2019-11-22 19:56:51 | user :: user_lpqskc0029@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-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 << <iq type="result" to="user_lpqskc0029@localhost/993927746-tigase-21" id="jWrlHPM3viW2iKHwdMFBDHv" xmlns="jabber:client" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push: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-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 << <iq type="result" to="user_lpqskc0029@localhost/993927746-tigase-21" id="p8ZeOMMIDoXgDxvrZ4DjTfu" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:51 | Disconnecting

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost/993927746-tigase-21 << <presence to="user_lpqskc0029@localhost" xmlns="jabber:client" from="user_lpqskc0029@localhost/993927746-tigase-21"><c node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-22 19:56:51 | [Mutex] notify: [disconnected]

2019-11-22 19:56:51 | [Mutex] received everything.

2019-11-22 19:56:51 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1">biwsbj11c2VyX2xwcXNrYzAwMjkscj1acmhPUE1JN2t2c1h1WDdhc1JhUQ==</auth>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1acmhPUE1JN2t2c1h1WDdhc1JhUUJJV0FuRGUyZDc0dFFFQ2lsZGU5LHM9Z2pzTzkwSlRPcCtkVWc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9WnJoT1BNSTdrdnNYdVg3YXNSYVFCSVdBbkRlMmQ3NHRRRUNpbGRlOSxwPVc1TVBGZ3BFYzh3cHRHcTQ5S3cvU0dZbzQ5VT0=</response>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:51 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:51 | [Mutex] notify: [event, disconnected]

2019-11-22 19:56:51 | [Mutex] received everything.

2019-11-22 19:56:51 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:51 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-1">biwsbj11c2VyX2xwcXNrYzAwMjkscj0yR0pWaTU5REhlSGE1UWRPUVU4Uw==</auth>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0yR0pWaTU5REhlSGE1UWRPUVU4UzY2bE9YT241VHFoTTFvdTBVVmZ3LHM9Z2pzTzkwSlRPcCtkVWc9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:51 | user :: user_lpqskc0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MkdKVmk1OURIZUhhNVFkT1FVOFM2NmxPWE9uNVRxaE0xb3UwVVZmdyxwPTFtSEczMFJMUnNxemdZK2NIRGtiRGxpblRsST0=</response>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:52 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:52 | [Mutex] received everything.

2019-11-22 19:56:52 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:52 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">AHVzZXJfbHBxc2tjMDAyOQAgLSAtIC0gLSAt</auth>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:52 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:52 | [Mutex] received everything.

2019-11-22 19:56:52 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:52 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2xwcXNrYzAwMjkscj1GSzB3NEJBYTE5SUJDMm5taEZ4Zw==</auth>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1GSzB3NEJBYTE5SUJDMm5taEZ4Z0luMDN6VnlJbk5tYW1NT2pWYnRBLHM9YkJoTGFiWXpyaEpNd0E9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9RkswdzRCQWExOUlCQzJubWhGeGdJbjAzelZ5SW5ObWFtTU9qVmJ0QSxwPTJkTXJONHVGck41UDkwOWtGTy9kZm5hREFMWGxMNUU1Snp6TUhPU0JHK0k9</response>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:52 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:52 | [Mutex] received everything.

2019-11-22 19:56:52 | [Mutex] isItemNotified: authFailed :: true

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2xwcXNrYzAwMjkscj1CZHFTQU9jZnRySnZlOVhLMm0xaQ==</auth>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1CZHFTQU9jZnRySnZlOVhLMm0xaTJlaFZtREVoYzFqbkJTR29WQ2VSLHM9YkJoTGFiWXpyaEpNd0E9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9QmRxU0FPY2Z0ckp2ZTlYSzJtMWkyZWhWbURFaGMxam5CU0dvVkNlUixwPStzYndqejBCelJuSUtjc2pORGhOODhwYjJGbVhQOE1CSU1sMlB3d0t1a2M9</response>

2019-11-22 19:56:52 | user :: user_lpqskc0029@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:54 | [Mutex] waiting for: [user]

2019-11-22 19:56:54 | [Mutex] received everything.

2019-11-22 19:56:54 | [Mutex] isItemNotified: user:ok:true :: true

2019-11-22 19:56:54 |

2019-11-22 19:56:54 | null / [TestClass name=class tigase.tests.server.TestBruteforcePrevention]

2019-11-22 19:56:54 | ------------------------------------

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@1935b423

2019-11-22 19:56:54 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@17f25975}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@13cddf53}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@56cf534e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@626f6118}, 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=Fri Nov 22 19:56:54 PST 2019}}}}

2019-11-22 19:56:54 | [Mutex] waiting for: [registration]

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@67d3a5d1

2019-11-22 19:56:54 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@17f25975}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@13cddf53}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@56cf534e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@626f6118}, 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=Fri Nov 22 19:56:54 PST 2019}}}}

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@1a99c93f

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

2019-11-22 19:56:54 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@36e0ac01}

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

2019-11-22 19:56:54 | user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2019-11-22 19:56:54 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3}

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@2f9e7c64

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@5519b96b

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

2019-11-22 19:56:54 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@19d42c6}

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

2019-11-22 19:56:54 | user :: null >> <iq to="localhost" id="MXmQX8Lxv8LV8Co0axczu5r" type="get"><query xmlns="jabber:iq:register"/></iq>

2019-11-22 19:56:54 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@18567bd7}

2019-11-22 19:56:54 | user :: null << <iq type="result" id="MXmQX8Lxv8LV8Co0axczu5r" xmlns="jabber:client" from="localhost"><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-22 19:56:54 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@32ae7650}

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@6e8f513d

2019-11-22 19:56:54 | user :: null >> <iq to="localhost" id="oil3o8q4iNIBydHP5hENDxx" type="set"><query xmlns="jabber:iq:register"><username>user_i8ll730030</username><password>user_i8ll730030</password><email>1244847f-cd61-46a7-bf77-6796f0c4e9c9@localhost</email></query></iq>

2019-11-22 19:56:54 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@4a42afa1}

2019-11-22 19:56:54 | user :: null << <iq type="result" id="oil3o8q4iNIBydHP5hENDxx" xmlns="jabber:client" from="localhost"/>

2019-11-22 19:56:54 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@76456f66}

2019-11-22 19:56:54 | [Mutex] received everything.

2019-11-22 19:56:54 | [Mutex] isItemNotified: registrationSuccess :: true

2019-11-22 19:56:54 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@36e0ac01, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@19d42c6]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@17f25975}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@13cddf53}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@56cf534e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@626f6118}, 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=Fri Nov 22 19:56:54 PST 2019}}}}

2019-11-22 19:56:54 | 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;@54704812}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@36e0ac01, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@19d42c6]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@17f25975}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@154d4229}, 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@13cddf53}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@56cf534e}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@626f6118}, 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=Fri Nov 22 19:56:54 PST 2019}}}}

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1da5981a

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@5cf87108

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@48eac513

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1589ad37

2019-11-22 19:56:54 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@24dd82d4

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

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2k4bGw3MzAwMzAscj02REV6cDdEVm9rM0d2Z1VLQmExdg==</auth>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj02REV6cDdEVm9rM0d2Z1VLQmExdmN4TE1NZ0hRNDRCbHhNMHJNRW5FLHM9WjVwUFBWQis2ZTd4b2c9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9NkRFenA3RFZvazNHdmdVS0JhMXZjeExNTWdIUTQ0Qmx4TTByTUVuRSxwPWJwNnF4NE1zWmNDZk9ncGU3MnVXRG5kRmFrcnppSzQra1BRMDdBY3JXOU09</response>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1Wd01ZMWNFY0x5Ni9SSUlZWFB4T3AxU2JJTTNzell6b054cndCOWlVNFBVPQ==</success>

2019-11-22 19:56:54 | user :: user_i8ll730030@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 node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="vxsZDK3itTHNCC9pNOSKIQZW/14=" hash="SHA-1"/></features>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <iq xmlns="jabber:client" id="KfaHziRWnLX2zxm4iOMLEti" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-22" id="KfaHziRWnLX2zxm4iOMLEti" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_i8ll730030@localhost/993927746-tigase-22</jid></bind></iq>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 >> <iq xmlns="jabber:client" id="FknLN36DFvaBqlHPM3viW2i" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-22" id="FknLN36DFvaBqlHPM3viW2i" xmlns="jabber:client"/>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 >> <iq to="localhost" id="0YhHKBtnbt2zbDJsl5lQX8L" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 << <enabled resume="true" id="7c6c8202-bc57-49a6-93d7-51903e6ae31c" xmlns="urn:xmpp:sm:3" max="60" location="d4270859fb27"/>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 >> <iq id="M2r0hM37FspdIMIEs7mT8n1" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:54 | user :: user_i8ll730030@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-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-22" id="0YhHKBtnbt2zbDJsl5lQX8L" xmlns="jabber:client" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push: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-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-22" id="M2r0hM37FspdIMIEs7mT8n1" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:54 | Disconnecting

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost/993927746-tigase-22 << <presence to="user_i8ll730030@localhost" xmlns="jabber:client" from="user_i8ll730030@localhost/993927746-tigase-22"><c node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-22 19:56:54 | [Mutex] notify: [disconnected]

2019-11-22 19:56:54 | [Mutex] received everything.

2019-11-22 19:56:54 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2k4bGw3MzAwMzAscj1xYk9BdkJISTBFV3EyVmJHZVZsbw==</auth>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1xYk9BdkJISTBFV3EyVmJHZVZsb3ZWVG0xd0N5MUhYb1lEenZQTjRoLHM9WjVwUFBWQis2ZTd4b2c9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9cWJPQXZCSEkwRVdxMlZiR2VWbG92VlRtMXdDeTFIWG9ZRHp2UE40aCxwPTh4eEdFbmlUTG5tNytFenl5akJ2Z3lXLzRaSFV5LytYOEtxUllQcE02R0U9</response>

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:54 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:54 | [Mutex] received everything.

2019-11-22 19:56:54 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:54 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:54 | user :: user_i8ll730030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2k4bGw3MzAwMzAscj1mRTJUODVrT0tOSVNPMTZ4c2YwWg==</auth>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1mRTJUODVrT0tOSVNPMTZ4c2YwWmtRT2ttMFdRSEFpWnBYWktSaXpLLHM9WjVwUFBWQis2ZTd4b2c9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9ZkUyVDg1a09LTklTTzE2eHNmMFprUU9rbTBXUUhBaVpwWFpLUml6SyxwPW16RmFwZ3dXUkhJMHNDR29XV3I2ZjE2MkRmVFNrZ0lDSk52V3liMDlVb2M9</response>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:55 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:55 | [Mutex] received everything.

2019-11-22 19:56:55 | [Mutex] isItemNotified: authFailed :: true

2019-11-22 19:56:55 | [Mutex] waiting for: [event]

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

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2k4bGw3MzAwMzAscj1PQ3R4MnhSQ0prQTkwcnVCTkczUA==</auth>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1PQ3R4MnhSQ0prQTkwcnVCTkczUEtHY1J6enpuSk9xblpDQ1d2eVlXLHM9WjVwUFBWQis2ZTd4b2c9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9T0N0eDJ4UkNKa0E5MHJ1Qk5HM1BLR2NSenp6bkpPcW5aQ0NXdnlZVyxwPVRlMUROSFZ0dXgzZ1Z5QnZ2K0w4V05GcE9hUFphUGoreTd6MkVRL1d6VlE9</response>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <failure xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><not-authorized/><text xml:lang="en">Password not verified</text></failure>

2019-11-22 19:56:55 | [Mutex] notify: [event, authFailed]

2019-11-22 19:56:55 | [Mutex] received everything.

2019-11-22 19:56:55 | [Mutex] isItemNotified: authFailed :: true

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

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

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

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyX2k4bGw3MzAwMzAscj1UTkJBakxTOUFKQlNTUkpFRHRKUQ==</auth>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1UTkJBakxTOUFKQlNTUkpFRHRKUWtSSUtYV081VThwRVZQblFWZ2JmLHM9WjVwUFBWQis2ZTd4b2c9PSxpPTQwOTY=</challenge>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9VE5CQWpMUzlBSkJTU1JKRUR0SlFrUklLWFdPNVU4cEVWUG5RVmdiZixwPU1nWXdVOTB1dWk3bVNXWnE1ODVKSHJEbk1KK3ZXWmZ2aGRBSEpFTkkwc3M9</response>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj12Y2xPVnQyczhZbHljRVMydEMwbjZnN2pmcVRSRFlmQUdJTVpWNGkwa244PQ==</success>

2019-11-22 19:56:55 | user :: user_i8ll730030@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 node="https://tigase.net/tigase-xmpp-server" xmlns="http://jabber.org/protocol/caps" ver="vxsZDK3itTHNCC9pNOSKIQZW/14=" hash="SHA-1"/></features>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost >> <iq xmlns="jabber:client" id="H9KVQ7Dr2lUjDHwxteOMMID" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-23" id="H9KVQ7Dr2lUjDHwxteOMMID" xmlns="jabber:client"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user_i8ll730030@localhost/993927746-tigase-23</jid></bind></iq>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 >> <iq xmlns="jabber:client" id="SkfAFJKQQUY9q1oT3xv7k9l" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-23" id="SkfAFJKQQUY9q1oT3xv7k9l" xmlns="jabber:client"/>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 >> <iq to="localhost" id="dUXj1dAr6HpfCJR0mSY6pW3" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 << <enabled resume="true" id="1115bb8e-1e97-45fa-8968-1a9ceb4f779c" xmlns="urn:xmpp:sm:3" max="60" location="d4270859fb27"/>

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 >> <iq id="M6v8xi5bNIVfMUYaubu9Ep5" type="get"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:55 | user :: user_i8ll730030@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-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-23" id="dUXj1dAr6HpfCJR0mSY6pW3" xmlns="jabber:client" from="localhost"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/f55efd39" category="component"/><identity type="im" name="Tigase ver. 8.1.0-SNAPSHOT-b10588/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="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/disco#info"/><feature var="http://jabber.org/protocol/disco#items"/><feature var="urn:xmpp:blocking"/><feature var="urn:xmpp:ping"/><feature var="urn:ietf:params:xml:ns:xmpp-sasl"/><feature var="http://jabber.org/protocol/pubsub"/><feature var="http://jabber.org/protocol/pubsub#owner"/><feature var="http://jabber.org/protocol/pubsub#publish"/><identity type="pep" category="pubsub"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push: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-22 19:56:55 | user :: user_i8ll730030@localhost/993927746-tigase-23 << <iq type="result" to="user_i8ll730030@localhost/993927746-tigase-23" id="M6v8xi5bNIVfMUYaubu9Ep5" xmlns="jabber:client"><query xmlns="jabber:iq:roster"/></iq>

2019-11-22 19:56:55 | Disconnecting

2019-11-22 19:56:55 | [Mutex] notify: [disconnected]

2019-11-22 19:56:55 | [Mutex] received everything.

2019-11-22 19:56:55 | user :: user_i8ll730030@localhost << <presence to="user_i8ll730030@localhost" xmlns="jabber:client" from="user_i8ll730030@localhost/993927746-tigase-23"><c node="http://tigase.org/jaxmpp" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2019-11-22 19:56:57 | [Mutex] waiting for: [user]

2019-11-22 19:56:57 | [Mutex] received everything.

2019-11-22 19:56:57 | [Mutex] isItemNotified: user:ok:true :: true