MAM: Support for XEP-0313 for PubSub - #4733

Test duration: 61.065s

Failed Configuration
tigase.tests.pubsub.TestPubSubMAM
setUp 60.776s
2020-08-03 00:01:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Using XEP-0077 mode!!!!

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector  state changed: null->connecting

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] DNS entry stored in session object: localhost:5222

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Preparing connection to [localhost:5222]

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector  state changed: connecting->connected

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Whitespace ping period is setted to nullms

2020-08-03 00:01:48 | [Mutex] waiting for: [registration]

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@2db4a5ae

2020-08-03 00:01:48 | StateChangedEvent{oldState=null, newState=connecting}

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@23735e68

2020-08-03 00:01:48 | StateChangedEvent{oldState=connecting, newState=connected}

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@274ccede

2020-08-03 00:01:48 | user1 :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | StanzaReceivedEvent{stanza=<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>}

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2879] Start TLS

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

2020-08-03 00:01:48 | user1 :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-08-03 00:01:48 | StanzaSendingEvent{stanza=<starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>}

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2881] Proceeding TLS

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2881] Start handshake

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@624e5453

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@6a5f0461

2020-08-03 00:01:48 | user1 :: null << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | StanzaReceivedEvent{stanza=<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>}

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

2020-08-03 00:01:48 | user1 :: null >> <iq to="test-domain.com" id="GdkiNOGoQVhAgZhCgCHpWXj" type="get"><query xmlns="jabber:iq:register"/></iq>

2020-08-03 00:01:48 | StanzaSendingEvent{stanza=<iq to="test-domain.com" id="GdkiNOGoQVhAgZhCgCHpWXj" type="get"><query xmlns="jabber:iq:register"/></iq>}

2020-08-03 00:01:48 | user1 :: null << <iq from="test-domain.com" id="GdkiNOGoQVhAgZhCgCHpWXj" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.

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

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

2020-08-03 00:01:48 | StanzaReceivedEvent{stanza=<iq from="test-domain.com" id="GdkiNOGoQVhAgZhCgCHpWXj" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:register"><instructions>Choose a user name and password for use with this service.

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

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

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@53e6975c

2020-08-03 00:01:48 | user1 :: null >> <iq to="test-domain.com" id="ikDSQ9BgycRJAfTaN3kVbNG" type="set"><query xmlns="jabber:iq:register"><username>user1_2fbysg0198</username><password>user1_2fbysg0198</password><email>8723fb4b-5ea8-4505-8345-b2ed7d04ddc7@localhost</email></query></iq>

2020-08-03 00:01:48 | StanzaSendingEvent{stanza=<iq to="test-domain.com" id="ikDSQ9BgycRJAfTaN3kVbNG" type="set"><query xmlns="jabber:iq:register"><username>user1_2fbysg0198</username><password>user1_2fbysg0198</password><email>8723fb4b-5ea8-4505-8345-b2ed7d04ddc7@localhost</email></query></iq>}

2020-08-03 00:01:48 | user1 :: null << <iq from="test-domain.com" id="ikDSQ9BgycRJAfTaN3kVbNG" xmlns="jabber:client" type="result"/>

2020-08-03 00:01:48 | StanzaReceivedEvent{stanza=<iq from="test-domain.com" id="ikDSQ9BgycRJAfTaN3kVbNG" xmlns="jabber:client" type="result"/>}

2020-08-03 00:01:48 | [Mutex] received everything.

2020-08-03 00:01:48 | [Mutex] isItemNotified: registrationSuccess :: true

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Connector  state changed: connected->disconnecting

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=1] Terminating XMPP Stream

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2881] Connector  state changed: disconnecting->disconnected

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=453 thread=2881] Stream terminated

2020-08-03 00:01:48 | StateChangedEvent{oldState=connected, newState=disconnecting}

2020-08-03 00:01:48 | StateChangedEvent{oldState=disconnecting, newState=disconnected}

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@121d99c0

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@261ab1c

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@108fdb3d

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@77b4e207

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@919b6a8

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector  state changed: null->connecting

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] DNS entry stored in session object: localhost:5222

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Preparing connection to [localhost:5222]

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Opening connection to localhost/127.0.0.1:5222

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Connector  state changed: connecting->connected

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=1] Whitespace ping period is setted to nullms

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-08-03 00:01:48 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2886] Start TLS

2020-08-03 00:01:48 |  << <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2888] Proceeding TLS

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=454 thread=2888] Start handshake

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-08-03 00:01:48 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><auth xmlns="http://jabber.org/features/iq-auth"/><register xmlns="http://jabber.org/features/iq-register"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism></mechanisms><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2020-08-03 00:01:48 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyMV8yZmJ5c2cwMTk4LHI9MU15d1RKVXQ3YU1jaFVLcUNlT1U=</auth>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyMV8yZmJ5c2cwMTk4LHI9MU15d1RKVXQ3YU1jaFVLcUNlT1U=</auth>

2020-08-03 00:01:48 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0xTXl3VEpVdDdhTWNoVUtxQ2VPVVBIakJSdWF5eUpoaTd1RmpYY3VMLHM9ZmQyUjM1NmJvR2p4bEE9PSxpPTQwOTY=</challenge>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj0xTXl3VEpVdDdhTWNoVUtxQ2VPVVBIakJSdWF5eUpoaTd1RmpYY3VMLHM9ZmQyUjM1NmJvR2p4bEE9PSxpPTQwOTY=</challenge>

2020-08-03 00:01:48 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MU15d1RKVXQ3YU1jaFVLcUNlT1VQSGpCUnVheXlKaGk3dUZqWGN1TCxwPTFEdlJQaWhTN3hsZjVDN2xvaEYvZUZCaVpDY3RrMVhjWmxTOFFIQnFuOGs9</response>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9MU15d1RKVXQ3YU1jaFVLcUNlT1VQSGpCUnVheXlKaGk3dUZqWGN1TCxwPTFEdlJQaWhTN3hsZjVDN2xvaEYvZUZCaVpDY3RrMVhjWmxTOFFIQnFuOGs9</response>

2020-08-03 00:01:48 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1UWXhQWWVQcFN5dmtJVjFCTnNZcHgxamVIT0hxSU5abXAwWnVKaXE2QjRFPQ==</success>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1UWXhQWWVQcFN5dmtJVjFCTnNZcHgxamVIT0hxSU5abXAwWnVKaXE2QjRFPQ==</success>

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2020-08-03 00:01:48 |  >> <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" ver="OQKA4xn1/Dd2U5AGL+j0n+zTjNU="/></features>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><register xmlns="http://jabber.org/features/iq-register"/><csi xmlns="urn:xmpp:csi:0"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session xmlns="urn:ietf:params:xml:ns:xmpp-session"><optional/></session><c hash="SHA-1" xmlns="http://jabber.org/protocol/caps" node="https://tigase.net/tigase-xmpp-server" ver="OQKA4xn1/Dd2U5AGL+j0n+zTjNU="/></features>

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2020-08-03 00:01:48 |  << <iq xmlns="jabber:client" id="xnKiR3xalTXfyjQKw6rjEof" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com >> <iq xmlns="jabber:client" id="xnKiR3xalTXfyjQKw6rjEof" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2020-08-03 00:01:48 |  >> <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="xnKiR3xalTXfyjQKw6rjEof" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user1_2fbysg0198@test-domain.com/2095755800-tigase-206</jid></bind></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com << <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="xnKiR3xalTXfyjQKw6rjEof" xmlns="jabber:client" type="result"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user1_2fbysg0198@test-domain.com/2095755800-tigase-206</jid></bind></iq>

2020-08-03 00:01:48 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2020-08-03 00:01:48 |  << <iq xmlns="jabber:client" id="MZyVhAgZhCgCHpWXj0tp2l1" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq xmlns="jabber:client" id="MZyVhAgZhCgCHpWXj0tp2l1" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2020-08-03 00:01:48 |  >> <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="MZyVhAgZhCgCHpWXj0tp2l1" xmlns="jabber:client" type="result"/>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="MZyVhAgZhCgCHpWXj0tp2l1" xmlns="jabber:client" type="result"/>

2020-08-03 00:01:48 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2020-08-03 00:01:48 |  << <iq to="test-domain.com" id="V2FnLEn9GP7sbHPKvdBx8ta" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq to="test-domain.com" id="V2FnLEn9GP7sbHPKvdBx8ta" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2020-08-03 00:01:48 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2020-08-03 00:01:48 |  >> <enabled resume="true" id="3f61b6a2-f428-4d5c-a7bb-8d0e52eb2b8c" location="9585556aacac" xmlns="urn:xmpp:sm:3" max="60"/>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <enabled resume="true" id="3f61b6a2-f428-4d5c-a7bb-8d0e52eb2b8c" location="9585556aacac" xmlns="urn:xmpp:sm:3" max="60"/>

2020-08-03 00:01:48 |  << <iq id="4ipPKvdBx8taoOGBDiGPXYY" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq id="4ipPKvdBx8taoOGBDiGPXYY" type="get"><query xmlns="jabber:iq:roster"/></iq>

2020-08-03 00:01:48 |  >> <iq from="test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="V2FnLEn9GP7sbHPKvdBx8ta" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.2.0-SNAPSHOT-b10978/ddedac76" category="component" type="router"/><identity name="Tigase ver. 8.2.0-SNAPSHOT-b10978/ddedac76" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</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 category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mix:pam:2#archive"/><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-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq from="test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="V2FnLEn9GP7sbHPKvdBx8ta" xmlns="jabber:client" type="result"><query xmlns="http://jabber.org/protocol/disco#info"><identity name="Tigase ver. 8.2.0-SNAPSHOT-b10978/ddedac76" category="component" type="router"/><identity name="Tigase ver. 8.2.0-SNAPSHOT-b10978/ddedac76" category="server" type="im"/><feature var="http://jabber.org/protocol/commands"/><x xmlns="jabber:x:data" type="result"><field var="FORM_TYPE" type="hidden"><value>http://jabber.org/network/serverinfo</value></field><field var="abuse-addresses" type="text-multi"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</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 category="pubsub" type="pep"/><feature var="urn:xmpp:pep-vcard-conversion:0"/><feature var="urn:xmpp:bookmarks-conversion:0"/><feature var="urn:xmpp:archive:auto"/><feature var="urn:xmpp:archive:manage"/><feature var="urn:xmpp:push:0"/><feature var="tigase:push:away:0"/><feature var="tigase:push:encrypt:0"/><feature var="tigase:push:encrypt:aes-128-gcm"/><feature var="tigase:push:filter:ignore-unknown:0"/><feature var="tigase:push:filter:groupchat:0"/><feature var="tigase:push:priority:0"/><feature var="tigase:push:filter:muted:0"/><feature var="tigase:push:jingle:0"/><feature var="jabber:iq:roster"/><feature var="jabber:iq:roster-dynamic"/><feature var="urn:xmpp:mam:1"/><feature var="urn:xmpp:mam:2"/><feature var="urn:xmpp:mix:pam:2#archive"/><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-08-03 00:01:48 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2020-08-03 00:01:48 |  >> <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="4ipPKvdBx8taoOGBDiGPXYY" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="4ipPKvdBx8taoOGBDiGPXYY" xmlns="jabber:client" type="result"><query xmlns="jabber:iq:roster"/></iq>

2020-08-03 00:01:48 | [Mutex] isItemNotified: root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839:exists :: false

2020-08-03 00:01:48 |  << <iq to="pubsub.test-domain.com" id="GjqubAeu2j38mbFoOITNIvp" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq to="pubsub.test-domain.com" id="GjqubAeu2j38mbFoOITNIvp" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:48 |  >> <presence from="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" to="user1_2fbysg0198@test-domain.com" xmlns="jabber:client"><c hash="sha-1" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-08-03 00:01:48 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <presence from="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" to="user1_2fbysg0198@test-domain.com" xmlns="jabber:client"><c hash="sha-1" xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2020-08-03 00:01:48 | [Mutex] waiting for: [root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839:create_node]

2020-08-03 00:01:49 |  >> <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="GjqubAeu2j38mbFoOITNIvp" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839"/></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="GjqubAeu2j38mbFoOITNIvp" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839"/></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839:create_node]

2020-08-03 00:01:49 | [Mutex] received everything.

2020-08-03 00:01:49 | [Mutex] isItemNotified: root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839:create_node:success :: true

2020-08-03 00:01:49 | [Mutex] isItemNotified: collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47:exists :: false

2020-08-03 00:01:49 |  << <iq to="pubsub.test-domain.com" id="uoDeVjQXfVWTZ9DkRWWUSZp" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field><field type="text-single" var="pubsub#collection"><value>root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq to="pubsub.test-domain.com" id="uoDeVjQXfVWTZ9DkRWWUSZp" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field><field type="text-single" var="pubsub#collection"><value>root-5dc6cc16-2a80-4c0a-9fa3-f285c49c2839</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47:create_node]

2020-08-03 00:01:49 |  >> <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="uoDeVjQXfVWTZ9DkRWWUSZp" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47"/></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="uoDeVjQXfVWTZ9DkRWWUSZp" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47"/></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47:create_node]

2020-08-03 00:01:49 | [Mutex] received everything.

2020-08-03 00:01:49 | [Mutex] isItemNotified: collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47:create_node:success :: true

2020-08-03 00:01:49 | [Mutex] isItemNotified: leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90:exists :: false

2020-08-03 00:01:49 |  << <iq to="pubsub.test-domain.com" id="o2xKXVY1rgZr6thZ49vbHs7" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90</value></field><field type="text-single" var="pubsub#max_items"><value>100</value></field><field type="text-single" var="pubsub#collection"><value>collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq to="pubsub.test-domain.com" id="o2xKXVY1rgZr6thZ49vbHs7" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90</value></field><field type="text-single" var="pubsub#max_items"><value>100</value></field><field type="text-single" var="pubsub#collection"><value>collection-cb7ce8a0-dd9e-4873-b036-f2c354f51e47</value></field></x></configure></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90:create_node]

2020-08-03 00:01:49 |  >> <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="o2xKXVY1rgZr6thZ49vbHs7" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"/></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="o2xKXVY1rgZr6thZ49vbHs7" xmlns="jabber:client" type="result"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"/></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90:create_node]

2020-08-03 00:01:49 | [Mutex] received everything.

2020-08-03 00:01:49 | [Mutex] isItemNotified: leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90:create_node:success :: true

2020-08-03 00:01:49 |  << <iq to="pubsub.test-domain.com" id="hgcoOIT5eu4BdybLUh1gNVh" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><publish node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"><item id="7b2548c5-4786-4410-800f-f985a4758872"><item xmlns="http://tigase.org/pubsub#test">Item: 7b2548c5-4786-4410-800f-f985a4758872</item></item></publish></pubsub></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 >> <iq to="pubsub.test-domain.com" id="hgcoOIT5eu4BdybLUh1gNVh" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><publish node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"><item id="7b2548c5-4786-4410-800f-f985a4758872"><item xmlns="http://tigase.org/pubsub#test">Item: 7b2548c5-4786-4410-800f-f985a4758872</item></item></publish></pubsub></iq>

2020-08-03 00:01:49 | [Mutex] waiting for: [publish:node:root:item-id:7b2548c5-4786-4410-800f-f985a4758872]

2020-08-03 00:01:49 |  >> <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="hgcoOIT5eu4BdybLUh1gNVh" xmlns="jabber:client" type="error"><pubsub xmlns="http://jabber.org/protocol/pubsub"><publish node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"><item id="7b2548c5-4786-4410-800f-f985a4758872"><item xmlns="http://tigase.org/pubsub#test">Item: 7b2548c5-4786-4410-800f-f985a4758872</item></item></publish></pubsub><error type="wait" code="500"><internal-server-error xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>

2020-08-03 00:01:49 | user1 :: user1_2fbysg0198@test-domain.com/2095755800-tigase-206 << <iq from="pubsub.test-domain.com" to="user1_2fbysg0198@test-domain.com/2095755800-tigase-206" id="hgcoOIT5eu4BdybLUh1gNVh" xmlns="jabber:client" type="error"><pubsub xmlns="http://jabber.org/protocol/pubsub"><publish node="leaf-c9818c5a-1a6c-45de-8ff3-67b91a9c5f90"><item id="7b2548c5-4786-4410-800f-f985a4758872"><item xmlns="http://tigase.org/pubsub#test">Item: 7b2548c5-4786-4410-800f-f985a4758872</item></item></publish></pubsub><error type="wait" code="500"><internal-server-error xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>

2020-08-03 00:02:49 | [Mutex] timeout. Not received [publish:node:root:item-id:7b2548c5-4786-4410-800f-f985a4758872]

2020-08-03 00:02:49 | [Mutex] isItemNotified: publish:node:root:item-id:7b2548c5-4786-4410-800f-f985a4758872 :: false

java.lang.AssertionError:
org.testng.AssertJUnit.fail(AssertJUnit.java:59)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:24)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:33)
tigase.tests.pubsub.TestPubSubMAM.publishItems(TestPubSubMAM.java:401)
tigase.tests.pubsub.TestPubSubMAM.setUp(TestPubSubMAM.java:95)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.base/java.lang.reflect.Method.invoke(Method.java:566)
org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:124)
org.testng.internal.MethodInvocationHelper.invokeMethodConsideringTimeout(MethodInvocationHelper.java:59)
org.testng.internal.Invoker.invokeConfigurationMethod(Invoker.java:458)
org.testng.internal.Invoker.invokeConfigurations(Invoker.java:222)
org.testng.internal.Invoker.invokeConfigurations(Invoker.java:142)
org.testng.internal.TestMethodWorker.invokeBeforeClassMethods(TestMethodWorker.java:168)
org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:105)
org.testng.TestRunner.privateRun(TestRunner.java:648)
org.testng.TestRunner.run(TestRunner.java:505)
org.testng.SuiteRunner.runTest(SuiteRunner.java:455)
org.testng.SuiteRunner.runSequentially(SuiteRunner.java:450)
org.testng.SuiteRunner.privateRun(SuiteRunner.java:415)
org.testng.SuiteRunner.run(SuiteRunner.java:364)
org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:84)
org.testng.TestNG.runSuitesSequentially(TestNG.java:1208)
org.testng.TestNG.runSuitesLocally(TestNG.java:1137)
org.testng.TestNG.runSuites(TestNG.java:1049)
org.testng.TestNG.run(TestNG.java:1017)
org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:283)
org.apache.maven.surefire.testng.TestNGXmlTestSuite.execute(TestNGXmlTestSuite.java:75)
org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:120)
org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
 
Skipped Configuration
tigase.tests.pubsub.TestPubSubMAM
setupMethod 0.000s
setupMethod 0.000s
setupMethod 0.000s
setupMethod 0.000s
tearDownClass 0.000s
tearDownMethod 0.000s
tearDownMethod 0.000s
tearDownMethod 0.000s
tearDownMethod 0.000s
Skipped Tests
tigase.tests.pubsub.TestPubSubMAM
testRetrieveWithLimitAndAfterFromLeaf 0.000s
testRetrieveWithTimestampsLimitAndAfterFromLeaf 0.000s
testRetriveAllFromLeaf 0.000s
testSupportAdvertisement 0.000s
2020-08-03 00:02:49 |

2020-08-03 00:02:49 | Running: REST: Node and item manipulation using REST API - XML - #4229, #4728

2020-08-03 00:02:49 | ------------------------------------