Test duration: 2.111s
| Passed Tests | ||
|---|---|---|
| tigase.tests.ExampleJaxmppTest | ||
| SimpleLoginTest | 1.150s |
2020-03-29 22:50:31 |
2020-03-29 22:50:31 | null / [TestClass name=class tigase.tests.ExampleJaxmppTest] 2020-03-29 22:50:31 | ------------------------------------ 2020-03-29 22:50:31 | This is test case 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Connector state changed: null->connecting 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] DNS entry stored in session object: localhost:5222 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Preparing connection to [localhost:5222] 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Connector state changed: connecting->connected 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Whitespace ping period is setted to nullms 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:31 | admin :: admin@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=3890] Start TLS 2020-03-29 22:50:31 | admin :: admin@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=3892] Proceeding TLS 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=3892] Start handshake 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:31 | admin :: admin@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-03-29 22:50:31 | admin :: admin@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj1hZG1pbixyPU1QS2NleHQ3ODRBdVdIZ1FIZmMx</auth> 2020-03-29 22:50:31 | admin :: admin@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1NUEtjZXh0Nzg0QXVXSGdRSGZjMUtzMWdGNDF5UnpueUZaYkR1RWdlLHM9TEtjc05TQVZPak5hR3c9PSxpPTQwOTY=</challenge> 2020-03-29 22:50:31 | admin :: admin@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9TVBLY2V4dDc4NEF1V0hnUUhmYzFLczFnRjQxeVJ6bnlGWmJEdUVnZSxwPUM3U1JvM0xPOWRobzBBUXVldW1rYWFldTVjVENsMzUrZzFid1h5RjRNTFE9</response> 2020-03-29 22:50:31 | admin :: admin@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1qUUo1bkVZNVlDcVVZbEszRUZ0dFZOTXVCSDZqUW1OYTcrWjBnbXlrbkZRPQ==</success> 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:31 | admin :: admin@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features> 2020-03-29 22:50:31 | admin :: admin@localhost >> <iq xmlns="jabber:client" id="dMsj07Br3zuodBADJUQWcFs" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-03-29 22:50:31 | admin :: admin@localhost << <iq xmlns="jabber:client" to="admin@localhost/2095755800-tigase-298" type="result" id="dMsj07Br3zuodBADJUQWcFs"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>admin@localhost/2095755800-tigase-298</jid></bind></iq> 2020-03-29 22:50:31 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 >> <iq xmlns="jabber:client" id="yIe6EJFBwgQNIVUcvnZ9LSa" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 << <iq xmlns="jabber:client" to="admin@localhost/2095755800-tigase-298" type="result" id="yIe6EJFBwgQNIVUcvnZ9LSa"/> 2020-03-29 22:50:31 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 >> <iq to="localhost" id="qMoACgMS1aKN2vdHIEClO7i" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 << <enabled location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3" resume="true" id="9b681c5e-758b-4469-8c65-d41cec46ea9d"/> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 >> <iq id="RG4TdBADJUQWcFskXm9vhSY" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 << <iq xmlns="jabber:client" to="admin@localhost/2095755800-tigase-298" type="result" from="localhost" id="qMoACgMS1aKN2vdHIEClO7i"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-03-29 22:50:31 | admin :: admin@localhost/2095755800-tigase-298 << <iq xmlns="jabber:client" to="admin@localhost/2095755800-tigase-298" type="result" id="RG4TdBADJUQWcFskXm9vhSY"><query xmlns="jabber:iq:roster"/></iq> 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Connector state changed: connected->disconnecting 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=1] Terminating XMPP Stream 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=3892] Connector state changed: disconnecting->disconnected 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=614 thread=3892] Stream terminated 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Using XEP-0077 mode!!!! 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Connector state changed: null->connecting 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@dcc21f3 2020-03-29 22:50:32 | StateChangedEvent{oldState=null, newState=connecting, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@3fe19757}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@30bce3a}, 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@7344a83}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4236c401}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@2e19b60d}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=localhost}, LOG_PREFIX=Entry{scope=user, value=test_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=Sun Mar 29 22:50:32 PDT 2020}}}} 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] DNS entry stored in session object: localhost:5222 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Preparing connection to [localhost:5222] 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Connector state changed: connecting->connected 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Whitespace ping period is setted to nullms 2020-03-29 22:50:32 | [Mutex] waiting for: [registration] 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@733ec7ea 2020-03-29 22:50:32 | StateChangedEvent{oldState=connecting, newState=connected, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@3fe19757}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@30bce3a}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@4119e0a3}, 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@7344a83}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4236c401}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@2e19b60d}, 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=test_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=Sun Mar 29 22:50:32 PDT 2020}}}} 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@56fbeb5c 2020-03-29 22:50:32 | test_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> 2020-03-29 22:50:32 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@22193b4e} 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=3897] Start TLS 2020-03-29 22:50:32 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-03-29 22:50:32 | test_user :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-03-29 22:50:32 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xml.DefaultElement@25881ca3} 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=3899] Proceeding TLS 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=3899] Start handshake 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@41d358de 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@44d7d699 2020-03-29 22:50:32 | test_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> 2020-03-29 22:50:32 | StanzaReceivedEvent{stanza=tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@4dff6454} 2020-03-29 22:50:32 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-03-29 22:50:32 | test_user :: null >> <iq to="localhost" id="4NnwfLQU8nSfyu5xjN3sk5l" type="get"><query xmlns="jabber:iq:register"/></iq> 2020-03-29 22:50:32 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@5e4a739f} 2020-03-29 22:50:32 | test_user :: null << <iq xmlns="jabber:client" type="result" from="localhost" id="4NnwfLQU8nSfyu5xjN3sk5l"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service. Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><x xmlns="jabber:x:data" type="form"><title>Account Registration</title><instructions>Choose a user name and password for use with this service. Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><field type="hidden" var="FORM_TYPE"><value>jabber:iq:register</value></field><field label="Username" type="text-single" var="username"><required/><value/></field><field label="Password" type="text-private" var="password"><required/><value/></field><field label="Email (MUST BE VALID!)" type="text-single" var="email"><required/><value/></field></x></query></iq> 2020-03-29 22:50:32 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@2657e5ee} 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@7d195c52 2020-03-29 22:50:32 | test_user :: null >> <iq to="localhost" id="EG0zs1aPIOJPTXn6CFvofXm" type="set"><query xmlns="jabber:iq:register"><username>test_user_ozgreb0550</username><password>test_user_ozgreb0550</password><email>736d85c0-ea62-496c-b4ac-dbfeaabebbdb@localhost</email></query></iq> 2020-03-29 22:50:32 | StanzaSendingEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@c14a32ac} 2020-03-29 22:50:32 | test_user :: null << <iq xmlns="jabber:client" type="result" from="localhost" id="EG0zs1aPIOJPTXn6CFvofXm"/> 2020-03-29 22:50:32 | StanzaReceivedEvent{stanza=tigase.jaxmpp.core.client.xmpp.stanzas.IQ@7002e76} 2020-03-29 22:50:32 | [Mutex] received everything. 2020-03-29 22:50:32 | [Mutex] isItemNotified: registrationSuccess :: true 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Connector state changed: connected->disconnecting 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=1] Terminating XMPP Stream 2020-03-29 22:50:32 | 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;@3fe19757}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@22193b4e, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@4dff6454]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@30bce3a}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@4119e0a3}, 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@7344a83}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4236c401}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@2e19b60d}, 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=test_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=Sun Mar 29 22:50:32 PDT 2020}}}} 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=3899] Connector state changed: disconnecting->disconnected 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=615 thread=3899] Stream terminated 2020-03-29 22:50:32 | 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;@3fe19757}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@22193b4e, tigase.jaxmpp.j2se.connectors.socket.SocketConnector$3@4dff6454]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@30bce3a}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@4119e0a3}, 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@7344a83}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@4236c401}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@2e19b60d}, 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=test_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=Sun Mar 29 22:50:32 PDT 2020}}}} 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@316b3706 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@288acc50 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset. 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session] 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@1fc6fedf 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream] 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@19b300cf 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@7188c7f8 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] Connector state changed: null->connecting 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] DNS entry stored in session object: localhost:5222 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] Preparing connection to [localhost:5222] 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] Opening connection to localhost/127.0.0.1:5222 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] Connector state changed: connecting->connected 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=1] Whitespace ping period is setted to nullms 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=3904] Start TLS 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=3906] Proceeding TLS 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=3906] Start handshake 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features> 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj10ZXN0X3VzZXJfb3pncmViMDU1MCxyPWdsS0tBRVoyS1laQlpJRmx4cjk4</auth> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1nbEtLQUVaMktZWkJaSUZseHI5OGwxcnZiaW42MzU4OXZ4aFRJT1RyLHM9SHRxdEZ1N1VHRWlmUnc9PSxpPTQwOTY=</challenge> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9Z2xLS0FFWjJLWVpCWklGbHhyOThsMXJ2YmluNjM1ODl2eGhUSU9UcixwPUdtSnBIc3VVRE5VSUtBakw4ck90Wks2OGlnQkNKbjdKaWplS2RMdUs4ZTQ9</response> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj0rUFVhblhWVHk5S1hOYUozY0ZGV1FPem1abXFqMnl6SWd5L0E3bWVYUVdJPQ==</success> 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c xmlns="http://jabber.org/protocol/caps" ver="5J2MYGBPASPm0KgC9Q33WNw9Sxc=" node="https://tigase.net/tigase-xmpp-server" hash="SHA-1"/></features> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost >> <iq xmlns="jabber:client" id="fLdS7t7HKUfFITfWU4sbuo5" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost << <iq xmlns="jabber:client" to="test_user_ozgreb0550@localhost/2095755800-tigase-299" type="result" id="fLdS7t7HKUfFITfWU4sbuo5"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>test_user_ozgreb0550@localhost/2095755800-tigase-299</jid></bind></iq> 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded. 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 >> <iq xmlns="jabber:client" id="NLbW3zuodBADJUQWcFskXm9" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 << <iq xmlns="jabber:client" to="test_user_ozgreb0550@localhost/2095755800-tigase-299" type="result" id="NLbW3zuodBADJUQWcFskXm9"/> 2020-03-29 22:50:32 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 >> <iq to="localhost" id="6CI2o0kLENEGtjRTfQ6DrgZ" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 << <enabled location="9585556aacac" max="60" xmlns="urn:xmpp:sm:3" resume="true" id="c5ebae4c-e962-48ac-95f1-6254192550c5"/> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 >> <iq id="uGSp5i0j4zlXeGGt4Dk2bJH" type="get"><query xmlns="jabber:iq:roster"/></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 << <iq xmlns="jabber:client" to="test_user_ozgreb0550@localhost/2095755800-tigase-299" type="result" from="localhost" id="6CI2o0kLENEGtjRTfQ6DrgZ"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="router" category="component"/><identity name="Tigase ver. 8.1.0-SNAPSHOT-b10703/4086d2cd" type="im" category="server"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field type="hidden" var="FORM_TYPE"><value>http://jabber.org/network/serverinfo</value></field><field type="text-multi" var="abuse-addresses"><value>mailto:abuse@localhost</value><value>xmpp:abuse@localhost</value></field></x><feature var="urn:xmpp:carbons:2"/><feature var="http://jabber.org/protocol/stats"/><feature var="vcard-temp"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><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:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:priority:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="jabber:iq:version"/><feature var="urn:xmpp:time"/><feature var="jabber:iq:privacy"/><feature var="urn:ietf:params:xml:ns:xmpp-bind"/><feature var="http://jabber.org/protocol/commands"/><feature var="urn:ietf:params:xml:ns:vcard-4.0"/><feature var="jabber:iq:private"/><feature var="urn:ietf:params:xml:ns:xmpp-session"/></query></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 << <iq xmlns="jabber:client" to="test_user_ozgreb0550@localhost/2095755800-tigase-299" type="result" id="uGSp5i0j4zlXeGGt4Dk2bJH"><query xmlns="jabber:iq:roster"/></iq> 2020-03-29 22:50:32 | test_user :: test_user_ozgreb0550@localhost/2095755800-tigase-299 << <presence xmlns="jabber:client" to="test_user_ozgreb0550@localhost" from="test_user_ozgreb0550@localhost/2095755800-tigase-299"><c xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" node="http://tigase.org/jaxmpp" hash="sha-1"/></presence> 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=3906] Connector state changed: connected->disconnected 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=616 thread=3906] Stream terminated 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted 2020-03-29 22:50:32 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted |