Generated by TestNG with ReportNG at 21:32 PST on Monday 14 November 2022
root@7c41cec37518 / Java 17.0.2 (Private Build) / Linux 4.14.281-212.502.amzn2.x86_64 (amd64)

MAM2#extended: Support for XEP-0313 for PubSub - #4733

Suites · Log Output

Test duration : 0.589s

Failed Configuration
tigase.tests.pubsub.TestPubSubMAM2Extended
setUp 0.407s
2022-11-14 21:47:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Using XEP-0077 mode!!!!

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Connector  state changed: null->connecting

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@2d22a36f

2022-11-14 21:47:39 | StateChangedEvent{oldState=null, newState=connecting}

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] DNS entry stored in session object: localhost:5222

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Preparing connection to [localhost:5222]

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Opening connection to localhost/127.0.0.1:5222

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Connector  state changed: connecting->connected

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Whitespace ping period is setted to nullms

2022-11-14 21:47:39 | [Mutex] waiting for: [registration]

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@23e548bb

2022-11-14 21:47:39 | StateChangedEvent{oldState=connecting, newState=connected}

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@27dac81f

2022-11-14 21:47:39 | user1 :: null << <features xmlns="http://etherx.jabber.org/streams"><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | StanzaReceivedEvent{stanza=<features xmlns="http://etherx.jabber.org/streams"><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>}

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=2972] Start TLS

2022-11-14 21:47:39 | user1 :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-11-14 21:47:39 | StanzaSendingEvent{stanza=<starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>}

2022-11-14 21:47:39 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=2974] Proceeding TLS

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=2974] Start handshake

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@62d84280

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$EncryptionEstablishedHandler$EncryptionEstablishedEvent@5a59ac6f

2022-11-14 21:47:39 | user1 :: null << <features xmlns="http://etherx.jabber.org/streams"><authentication xmlns="urn:xmpp:sasl:2"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><inline><sm xmlns="urn:xmpp:sm:3"/><bind xmlns="urn:xmpp:bind:0"><inline><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:sm:3"/></inline></bind></inline></authentication><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | StanzaReceivedEvent{stanza=<features xmlns="http://etherx.jabber.org/streams"><authentication xmlns="urn:xmpp:sasl:2"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><inline><sm xmlns="urn:xmpp:sm:3"/><bind xmlns="urn:xmpp:bind:0"><inline><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:sm:3"/></inline></bind></inline></authentication><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>}

2022-11-14 21:47:39 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><authentication xmlns="urn:xmpp:sasl:2"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><inline><sm xmlns="urn:xmpp:sm:3"/><bind xmlns="urn:xmpp:bind:0"><inline><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:sm:3"/></inline></bind></inline></authentication><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2022-11-14 21:47:39 | user1 :: null >> <iq to="test-domain.com" id="r0IZhEi1ctdQS4w8EDunfxl" type="get"><query xmlns="jabber:iq:register"/></iq>

2022-11-14 21:47:39 | StanzaSendingEvent{stanza=<iq to="test-domain.com" id="r0IZhEi1ctdQS4w8EDunfxl" type="get"><query xmlns="jabber:iq:register"/></iq>}

2022-11-14 21:47:39 | user1 :: null << <iq type="result" id="r0IZhEi1ctdQS4w8EDunfxl" from="test-domain.com" xmlns="jabber:client"><query xmlns="jabber:iq:register"><instructions>Please provide the following information to sign up for an account

Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><x type="form" xmlns="jabber:x:data"><title>Account Registration</title><instructions>Please provide the following information to sign up for an account

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 type="text-single" label="Username" var="username"><required/><value/></field><field type="text-private" label="Password" var="password"><required/><value/></field><field type="text-single" label="Email (MUST BE VALID!)" var="email"><required/><value/></field></x></query></iq>

2022-11-14 21:47:39 | StanzaReceivedEvent{stanza=<iq type="result" id="r0IZhEi1ctdQS4w8EDunfxl" from="test-domain.com" xmlns="jabber:client"><query xmlns="jabber:iq:register"><instructions>Please provide the following information to sign up for an account

Please also provide your e-mail address (must be valid!) to which we will send confirmation link.</instructions><x type="form" xmlns="jabber:x:data"><title>Account Registration</title><instructions>Please provide the following information to sign up for an account

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 type="text-single" label="Username" var="username"><required/><value/></field><field type="text-private" label="Password" var="password"><required/><value/></field><field type="text-single" label="Email (MUST BE VALID!)" var="email"><required/><value/></field></x></query></iq>}

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.registration.InBandRegistrationModule$ReceivedRequestedFieldsHandler$ReceivedRequestedFieldsEvent@53f0870f

2022-11-14 21:47:39 | user1 :: null >> <iq to="test-domain.com" id="AjBXeQMWdEHTPVeDKU3nU9y" type="set"><query xmlns="jabber:iq:register"><username>user1_b6ig2v0200</username><password>user1_b6ig2v0200</password><email>e78c4918-b4b3-49b0-b271-5863d756c216@localhost</email></query></iq>

2022-11-14 21:47:39 | StanzaSendingEvent{stanza=<iq to="test-domain.com" id="AjBXeQMWdEHTPVeDKU3nU9y" type="set"><query xmlns="jabber:iq:register"><username>user1_b6ig2v0200</username><password>user1_b6ig2v0200</password><email>e78c4918-b4b3-49b0-b271-5863d756c216@localhost</email></query></iq>}

2022-11-14 21:47:39 | user1 :: null << <iq type="result" id="AjBXeQMWdEHTPVeDKU3nU9y" from="test-domain.com" xmlns="jabber:client"/>

2022-11-14 21:47:39 | StanzaReceivedEvent{stanza=<iq type="result" id="AjBXeQMWdEHTPVeDKU3nU9y" from="test-domain.com" xmlns="jabber:client"/>}

2022-11-14 21:47:39 | [Mutex] received everything.

2022-11-14 21:47:39 | [Mutex] isItemNotified: registrationSuccess :: true

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Connector  state changed: connected->disconnecting

2022-11-14 21:47:39 | StateChangedEvent{oldState=connected, newState=disconnecting}

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=1] Terminating XMPP Stream

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=2974] Connector  state changed: disconnecting->disconnected

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=468 thread=2974] Stream terminated

2022-11-14 21:47:39 | StateChangedEvent{oldState=disconnecting, newState=disconnected}

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@229b375e

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@2f7bdcf

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.reset: Reset.

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [session]

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@15da432a

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@42456661

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.Worker.interrupt: Worker Interrupted

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@399f98b0

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.AbstractSessionObject.clear: Clearing properties in scopes [stream]

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.Jaxmpp.login: state=disconnected cr=false

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.Jaxmpp.createConnector: Using SocketConnector

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] Connector  state changed: null->connecting

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] DNS entry stored in session object: localhost:5222

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] Preparing connection to [localhost:5222]

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] Opening connection to localhost/127.0.0.1:5222

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] Connector  state changed: connecting->connected

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=1] Whitespace ping period is setted to nullms

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-11-14 21:47:39 |  >> <features xmlns="http://etherx.jabber.org/streams"><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=2979] Start TLS

2022-11-14 21:47:39 |  << <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=2981] Proceeding TLS

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.connectors.socket.SocketConnector.printLog: [scid=469 thread=2981] Start handshake

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-11-14 21:47:39 |  >> <features xmlns="http://etherx.jabber.org/streams"><authentication xmlns="urn:xmpp:sasl:2"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><inline><sm xmlns="urn:xmpp:sm:3"/><bind xmlns="urn:xmpp:bind:0"><inline><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:sm:3"/></inline></bind></inline></authentication><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com << <features xmlns="http://etherx.jabber.org/streams"><authentication xmlns="urn:xmpp:sasl:2"><mechanism>SCRAM-SHA-512</mechanism><mechanism>SCRAM-SHA-256</mechanism><mechanism>SCRAM-SHA-1</mechanism><mechanism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><inline><sm xmlns="urn:xmpp:sm:3"/><bind xmlns="urn:xmpp:bind:0"><inline><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:sm:3"/></inline></bind></inline></authentication><auth xmlns="http://jabber.org/features/iq-auth"/><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><register xmlns="http://jabber.org/features/iq-register"/><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedtrue, nonSaslSupported: true

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.login: Authenticating with SASL Non-SASL

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.SaslModule.login: Try login with SASL

2022-11-14 21:47:39 |  << <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyMV9iNmlnMnYwMjAwLHI9T2JkTFNyVzV6MGxwTURHd3lvNjI=</auth>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com >> <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="SCRAM-SHA-256">biwsbj11c2VyMV9iNmlnMnYwMjAwLHI9T2JkTFNyVzV6MGxwTURHd3lvNjI=</auth>

2022-11-14 21:47:39 |  >> <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1PYmRMU3JXNXowbHBNREd3eW82Mlc1SjhLYmFhQ3ByUnJJRk9wb3FXLHM9dS9HTjlrU0l0LzN4WHc9PSxpPTQwOTY=</challenge>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com << <challenge xmlns="urn:ietf:params:xml:ns:xmpp-sasl">cj1PYmRMU3JXNXowbHBNREd3eW82Mlc1SjhLYmFhQ3ByUnJJRk9wb3FXLHM9dS9HTjlrU0l0LzN4WHc9PSxpPTQwOTY=</challenge>

2022-11-14 21:47:39 |  << <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9T2JkTFNyVzV6MGxwTURHd3lvNjJXNUo4S2JhYUNwclJySUZPcG9xVyxwPWNxZno2clN2MDljalphOXBPOTR0VnVwNHo0akFKSTlhcmE2Nkp1c0ViWW89</response>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com >> <response xmlns="urn:ietf:params:xml:ns:xmpp-sasl">Yz1iaXdzLHI9T2JkTFNyVzV6MGxwTURHd3lvNjJXNUo4S2JhYUNwclJySUZPcG9xVyxwPWNxZno2clN2MDljalphOXBPOTR0VnVwNHo0akFKSTlhcmE2Nkp1c0ViWW89</response>

2022-11-14 21:47:39 |  >> <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1VaGp5MUZWK3dKUzNiUTBKQXE4NytzTGMxSnZhdHg3anV2S3prRDhDdk9VPQ==</success>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com << <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl">dj1VaGp5MUZWK3dKUzNiUTBKQXE4NytzTGMxSnZhdHg3anV2S3prRDhDdk9VPQ==</success>

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.StreamFeaturesModule.streamRestarted: Pipelining is disabled

2022-11-14 21:47:39 |  >> <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"/><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" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="k0480KEozNmXlA6mNmOhceeOXvM="/></features>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@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"/><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" hash="SHA-1" xmlns="http://jabber.org/protocol/caps" ver="k0480KEozNmXlA6mNmOhceeOXvM="/></features>

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.auth.AuthModule.isAuthAvailable: saslSupportedfalse, nonSaslSupported: false

2022-11-14 21:47:39 |  << <iq xmlns="jabber:client" id="u0ER9sZfU0k2aILKThBtkP8" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com >> <iq xmlns="jabber:client" id="u0ER9sZfU0k2aILKThBtkP8" type="set"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource/></bind></iq>

2022-11-14 21:47:39 |  >> <iq type="result" id="u0ER9sZfU0k2aILKThBtkP8" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user1_b6ig2v0200@test-domain.com/228467168-tigase-219</jid></bind></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com << <iq type="result" id="u0ER9sZfU0k2aILKThBtkP8" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><jid>user1_b6ig2v0200@test-domain.com/228467168-tigase-219</jid></bind></iq>

2022-11-14 21:47:39 | tigase.jaxmpp.j2se.Jaxmpp.onResourceBindSuccess: Connected. Resource binded.

2022-11-14 21:47:39 |  << <iq xmlns="jabber:client" id="zccnWcMEGHCDLzpczCExlQ1" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <iq xmlns="jabber:client" id="zccnWcMEGHCDLzpczCExlQ1" type="set"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>

2022-11-14 21:47:39 |  >> <iq type="result" id="zccnWcMEGHCDLzpczCExlQ1" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"/>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <iq type="result" id="zccnWcMEGHCDLzpczCExlQ1" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"/>

2022-11-14 21:47:39 | tigase.jaxmpp.core.client.xmpp.modules.streammng.StreamManagementModule.enable: Enabling stream management

2022-11-14 21:47:39 |  << <iq to="test-domain.com" id="N7VTcDxeNQP1akX00r29zyi" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <iq to="test-domain.com" id="N7VTcDxeNQP1akX00r29zyi" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>

2022-11-14 21:47:39 |  << <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <enable xmlns="urn:xmpp:sm:3" resume="true"/>

2022-11-14 21:47:39 |  << <iq id="Udb4v8gPKupY1j2gQ4eQ1gV" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <iq id="Udb4v8gPKupY1j2gQ4eQ1gV" type="get"><query xmlns="jabber:iq:roster"/></iq>

2022-11-14 21:47:39 |  >> <enabled location="7c41cec37518" id="9379895b-1ed8-4ae9-a618-6de99bb0f039" max="60" xmlns="urn:xmpp:sm:3" resume="true"/>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <enabled location="7c41cec37518" id="9379895b-1ed8-4ae9-a618-6de99bb0f039" max="60" xmlns="urn:xmpp:sm:3" resume="true"/>

2022-11-14 21:47:39 |  << <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <presence><c xmlns="http://jabber.org/protocol/caps" node="http://tigase.org/jaxmpp" ver="mg5bXbOeu1848vxdgu3jELgbQF8=" hash="sha-1"/></presence>

2022-11-14 21:47:39 |  >> <iq type="result" id="N7VTcDxeNQP1akX00r29zyi" from="test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" category="component" name="Tigase ver. 8.3.0-SNAPSHOT-b12146/cccf702b"/><identity type="im" category="server" name="Tigase ver. 8.3.0-SNAPSHOT-b12146/cccf702b"/><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="list-multi" var="abuse-addresses"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="urn:xmpp:sasl:2"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><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:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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: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:mam:2#extended"/><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:xmpp:extdisco:2"/><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>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <iq type="result" id="N7VTcDxeNQP1akX00r29zyi" from="test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><query xmlns="http://jabber.org/protocol/disco#info"><identity type="router" category="component" name="Tigase ver. 8.3.0-SNAPSHOT-b12146/cccf702b"/><identity type="im" category="server" name="Tigase ver. 8.3.0-SNAPSHOT-b12146/cccf702b"/><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="list-multi" var="abuse-addresses"><value>mailto:abuse@test-domain.com</value><value>xmpp:abuse@test-domain.com</value></field></x><feature var="urn:xmpp:mix:pam:2"/><feature var="urn:xmpp:carbons:2"/><feature var="urn:xmpp:carbons:rules:0"/><feature var="urn:xmpp:sasl:2"/><feature var="jabber:iq:auth"/><feature var="http://jabber.org/protocol/amp"/><feature var="msgoffline"/><feature var="vcard-temp"/><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:reporting:0"/><feature var="urn:xmpp:reporting:abuse:0"/><feature var="urn:xmpp:reporting:spam:0"/><feature var="urn:xmpp:reporting:1"/><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: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:mam:2#extended"/><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:xmpp:extdisco:2"/><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>

2022-11-14 21:47:39 | [Mutex] isItemNotified: root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6:exists :: false

2022-11-14 21:47:39 |  >> <iq type="result" id="Udb4v8gPKupY1j2gQ4eQ1gV" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><query xmlns="jabber:iq:roster"/></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <iq type="result" id="Udb4v8gPKupY1j2gQ4eQ1gV" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><query xmlns="jabber:iq:roster"/></iq>

2022-11-14 21:47:39 |  >> <presence from="user1_b6ig2v0200@test-domain.com/228467168-tigase-219" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <presence from="user1_b6ig2v0200@test-domain.com/228467168-tigase-219" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com"><c node="http://tigase.org/jaxmpp" hash="sha-1" xmlns="http://jabber.org/protocol/caps" ver="mg5bXbOeu1848vxdgu3jELgbQF8="/></presence>

2022-11-14 21:47:39 |  >> <message from="test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com"><body>Wecome to bb932a6b-caf9-4b64-a541-634aef9b33a1
This message was set at: Mon Nov 14 21:33:40 PST 2022</body><delay stamp="2022-11-15T05:47:39.326Z" from="test-domain.com" xmlns="urn:xmpp:delay">Offline Storage - 7c41cec37518</delay></message>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <message from="test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com"><body>Wecome to bb932a6b-caf9-4b64-a541-634aef9b33a1
This message was set at: Mon Nov 14 21:33:40 PST 2022</body><delay stamp="2022-11-15T05:47:39.326Z" from="test-domain.com" xmlns="urn:xmpp:delay">Offline Storage - 7c41cec37518</delay></message>

2022-11-14 21:47:39 |  << <iq to="pubsub.test-domain.com" id="p7REqQZ48HybDGyqeyujQ5F" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 >> <iq to="pubsub.test-domain.com" id="p7REqQZ48HybDGyqeyujQ5F" type="set"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6"/><configure><x xmlns="jabber:x:data" type="submit"><field type="text-single" var="pubsub#title"><value>root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub></iq>

2022-11-14 21:47:39 | [Mutex] waiting for: [root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6:create_node]

2022-11-14 21:47:39 |  >> <iq type="error" id="p7REqQZ48HybDGyqeyujQ5F" from="pubsub.test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6"/><configure><x type="submit" xmlns="jabber:x:data"><field type="text-single" var="pubsub#title"><value>root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub><error type="wait" code="500"><internal-server-error xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>

2022-11-14 21:47:39 | user1 :: user1_b6ig2v0200@test-domain.com/228467168-tigase-219 << <iq type="error" id="p7REqQZ48HybDGyqeyujQ5F" from="pubsub.test-domain.com" xmlns="jabber:client" to="user1_b6ig2v0200@test-domain.com/228467168-tigase-219"><pubsub xmlns="http://jabber.org/protocol/pubsub"><create node="root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6"/><configure><x type="submit" xmlns="jabber:x:data"><field type="text-single" var="pubsub#title"><value>root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6</value></field><field type="text-single" var="pubsub#node_type"><value>collection</value></field></x></configure></pubsub><error type="wait" code="500"><internal-server-error xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/></error></iq>

2022-11-14 21:47:39 | [Mutex] received everything.

2022-11-14 21:47:39 | [Mutex] isItemNotified: root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6:create_node:success :: false

java.lang.AssertionError: PubSub node root-8f172f6e-31c4-41b6-9b0a-2bfd4ad139f6 not created expected [true] but found [false]
org.testng.Assert.fail(Assert.java:110)
org.testng.Assert.failNotEquals(Assert.java:1413)
org.testng.Assert.assertTrue(Assert.java:56)
tigase.tests.utils.PubSubManager.createNode(PubSubManager.java:220)
tigase.tests.utils.PubSubNodeBuilder.build(PubSubNodeBuilder.java:68)
tigase.tests.pubsub.TestPubSubMAM2Extended.setUp(TestPubSubMAM2Extended.java:92)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
java.base/java.lang.reflect.Method.invoke(Method.java:568)
org.testng.internal.invokers.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:135)
org.testng.internal.invokers.MethodInvocationHelper.invokeMethodConsideringTimeout(MethodInvocationHelper.java:65)
org.testng.internal.invokers.ConfigInvoker.invokeConfigurationMethod(ConfigInvoker.java:381)
org.testng.internal.invokers.ConfigInvoker.invokeConfigurations(ConfigInvoker.java:319)
org.testng.internal.invokers.TestMethodWorker.invokeBeforeClassMethods(TestMethodWorker.java:178)
org.testng.internal.invokers.TestMethodWorker.run(TestMethodWorker.java:122)
java.base/java.util.ArrayList.forEach(ArrayList.java:1511)
org.testng.TestRunner.privateRun(TestRunner.java:808)
org.testng.TestRunner.run(TestRunner.java:603)
org.testng.SuiteRunner.runTest(SuiteRunner.java:429)
org.testng.SuiteRunner.runSequentially(SuiteRunner.java:423)
org.testng.SuiteRunner.privateRun(SuiteRunner.java:383)
org.testng.SuiteRunner.run(SuiteRunner.java:326)
org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:95)
org.testng.TestNG.runSuitesSequentially(TestNG.java:1249)
org.testng.TestNG.runSuitesLocally(TestNG.java:1169)
org.testng.TestNG.runSuites(TestNG.java:1092)
org.testng.TestNG.run(TestNG.java:1060)
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.TestPubSubMAM2Extended
cleanUp 0.000s
setupMethod 0.000s
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
tearDownMethod 0.000s
Skipped Tests
tigase.tests.pubsub.TestPubSubMAM2Extended
testRetrieveWithAfterIdAndBeforeIdFromLeaf 0.000s
2022-11-14 21:47:39 |

2022-11-14 21:47:39 | null / [TestClass name=class tigase.tests.pubsub.TestPubSubMAM2Extended]

2022-11-14 21:47:39 | ------------------------------------

testRetrieveWithAfterIdFromLeaf 0.000s
2022-11-14 21:47:39 |

2022-11-14 21:47:39 | null / [TestClass name=class tigase.tests.pubsub.TestPubSubMAM2Extended]

2022-11-14 21:47:39 | ------------------------------------

testRetrieveWithLimitAndAfterFromLeaf 0.000s
2022-11-14 21:47:39 |

2022-11-14 21:47:39 | null / [TestClass name=class tigase.tests.pubsub.TestPubSubMAM2Extended]

2022-11-14 21:47:39 | ------------------------------------

testRetriveAllFromLeaf 0.000s
2022-11-14 21:47:39 |

2022-11-14 21:47:39 | null / [TestClass name=class tigase.tests.pubsub.TestPubSubMAM2Extended]

2022-11-14 21:47:39 | ------------------------------------

testSupportAdvertisement 0.000s
2022-11-14 21:47:39 |

2022-11-14 21:47:39 | Running: REST: Node and item manipulation using REST API - XML - #4229, #4728

2022-11-14 21:47:39 | ------------------------------------