Test Results Report - WebSocket: Test WebSocket connectivity

Failed Configuration
tigase.tests.server.TestWebSocketConnectivity
setUp	90.052s	2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@5944f478 2020-08-25 18:28:54 \| StateChangedEvent{oldState=null, newState=connecting} 2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@53bc9f08 2020-08-25 18:28:54 \| StateChangedEvent{oldState=connecting, newState=connected} 2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@16be0b1b 2020-08-25 18:28:54 \| [Mutex] waiting for: [registration] 2020-08-25 18:28:54 \| ws_ :: 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-08-25 18:28:54 \| StanzaReceivedEvent{stanza=<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-08-25 18:28:54 \| StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>] 2020-08-25 18:28:54 \| ws_ :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/> 2020-08-25 18:28:54 \| StanzaSendingEvent{stanza=<starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>} 2020-08-25 18:28:54 \| ErrorEvent{condition=null, caught=javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@2d96c13d}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[<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>]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@6827ca80}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@27625ca5}, 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@1011c39e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7691f454}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@203443cc}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=test-domain.com}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=1}, LOG_PREFIX=Entry{scope=user, value=ws_}, 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=Tue Aug 25 18:28:54 PDT 2020}}}} 2020-08-25 18:28:54 \| StateChangedEvent{oldState=connected, newState=disconnected} 2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1586a41b 2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@b158806 2020-08-25 18:28:54 \| tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@72dfee64 2020-08-25 18:30:24 \| [Mutex] timeout. Not received [registration] 2020-08-25 18:30:24 \| [Mutex] isItemNotified: registrationSuccess :: false java.lang.AssertionError: Registration failed! org.testng.AssertJUnit.fail(AssertJUnit.java:65) org.testng.AssertJUnit.assertTrue(AssertJUnit.java:23) tigase.tests.utils.AccountsManager.registerAccount(AccountsManager.java:195) tigase.tests.utils.AccountBuilder.build(AccountBuilder.java:123) tigase.tests.server.TestWebSocketConnectivity.setUp(TestWebSocketConnectivity.java:76) 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:132) org.testng.internal.MethodInvocationHelper.invokeMethodConsideringTimeout(MethodInvocationHelper.java:61) org.testng.internal.ConfigInvoker.invokeConfigurationMethod(ConfigInvoker.java:366) org.testng.internal.ConfigInvoker.invokeConfigurations(ConfigInvoker.java:320) org.testng.internal.TestInvoker.runConfigMethods(TestInvoker.java:701) org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:527) org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:174) org.testng.internal.MethodRunner.runInSequence(MethodRunner.java:46) org.testng.internal.TestInvoker$MethodInvocationAgent.invoke(TestInvoker.java:822) org.testng.internal.TestInvoker.invokeTestMethods(TestInvoker.java:147) org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:146) org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:128) java.base/java.util.ArrayList.forEach(ArrayList.java:1541) org.testng.TestRunner.privateRun(TestRunner.java:764) org.testng.TestRunner.run(TestRunner.java:585) org.testng.SuiteRunner.runTest(SuiteRunner.java:384) org.testng.SuiteRunner.runSequentially(SuiteRunner.java:378) org.testng.SuiteRunner.privateRun(SuiteRunner.java:337) org.testng.SuiteRunner.run(SuiteRunner.java:286) org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:53) org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:96) org.testng.TestNG.runSuitesSequentially(TestNG.java:1218) org.testng.TestNG.runSuitesLocally(TestNG.java:1140) org.testng.TestNG.runSuites(TestNG.java:1069) org.testng.TestNG.run(TestNG.java:1037) 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.server.TestWebSocketConnectivity
setUp	-1598405424.208s
setUp	-1598405424.207s
setupMethod	-1598405424.207s
setupMethod	-1598405424.208s
tearDownClass	-1598405424.214s
tearDownMethod	-1598405424.208s
tearDownMethod	-1598405424.204s
tearDownMethod	-1598405424.214s

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.SessionObject$ClearedHandler$ClearedEvent@5944f478

2020-08-25 18:28:54 | StateChangedEvent{oldState=null, newState=connecting}

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.Connector$StreamRestartedHandler$StreamRestaredEvent@53bc9f08

2020-08-25 18:28:54 | StateChangedEvent{oldState=connecting, newState=connected}

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.Connector$ConnectedHandler$ConnectedEvent@16be0b1b

2020-08-25 18:28:54 | [Mutex] waiting for: [registration]

2020-08-25 18:28:54 | ws_ :: 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-08-25 18:28:54 | StanzaReceivedEvent{stanza=<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-08-25 18:28:54 | StreamFeaturesReceivedEvent[<features xmlns="http://etherx.jabber.org/streams"><sm xmlns="urn:xmpp:sm:3"/><mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/><register xmlns="http://jabber.org/features/iq-register"/><ver xmlns="urn:xmpp:features:rosterver"/><sub xmlns="urn:xmpp:features:pre-approval"/><starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"><required/></starttls><compression xmlns="http://jabber.org/features/compress"><method>zlib</method></compression></features>]

2020-08-25 18:28:54 | ws_ :: null >> <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>

2020-08-25 18:28:54 | StanzaSendingEvent{stanza=<starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"/>}

2020-08-25 18:28:54 | ErrorEvent{condition=null, caught=javax.net.ssl.SSLHandshakeException: Remote host terminated the handshake, sessionObject=AbstractSessionObject{properties={TRUST_MANAGERS_KEY=Entry{scope=user, value=[Ljavax.net.ssl.X509TrustManager;@2d96c13d}, CONNECTOR#DISABLEKEEPALIVE=Entry{scope=stream, value=false}, StreamFeaturesModule#STREAMS_FEATURES_LIST=Entry{scope=session, value=[<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>]}, STREAMS_MANAGER_KEY=Entry{scope=user, value=tigase.jaxmpp.core.client.xmpp.stream.XmppStreamsManager@6827ca80}, COMPRESSION_DISABLED=Entry{scope=user, value=true}, HOSTNAME_VERIFIER_KEY=Entry{scope=session, value=tigase.jaxmpp.j2se.connectors.socket.DefaultHostnameVerifier@27625ca5}, 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@1011c39e}, PresenceModule#PRESENCE_STORE=Entry{scope=user, value=tigase.jaxmpp.j2se.J2SEPresenceStore@7691f454}, ResponseManager#RESPONSE_MANAGER=Entry{scope=user, value=tigase.jaxmpp.j2se.ThreadSafeResponseManager@203443cc}, StreamFeaturesModule#PIPELINING_ACTIVE=Entry{scope=session, value=false}, DEFAULT_XMPP_STREAM_KEY=Entry{scope=session, value=DefaultXMPPStream}, domainName=Entry{scope=user, value=test-domain.com}, StreamFeaturesModule#EMBEDDED_STREAMS_COUNTER=Entry{scope=session, value=1}, LOG_PREFIX=Entry{scope=user, value=ws_}, 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=Tue Aug 25 18:28:54 PDT 2020}}}}

2020-08-25 18:28:54 | StateChangedEvent{oldState=connected, newState=disconnected}

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.Connector$StreamTerminatedHandler$StreamTerminatedEvent@1586a41b

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.Connector$DisconnectedHandler$DisconnectedEvent@b158806

2020-08-25 18:28:54 | tigase.jaxmpp.core.client.JaxmppCore$LoggedOutHandler$LoggedOutEvent@72dfee64

2020-08-25 18:30:24 | [Mutex] timeout. Not received [registration]

2020-08-25 18:30:24 | [Mutex] isItemNotified: registrationSuccess :: false

org.testng.AssertJUnit.fail(AssertJUnit.java:65)
org.testng.AssertJUnit.assertTrue(AssertJUnit.java:23)
tigase.tests.utils.AccountsManager.registerAccount(AccountsManager.java:195)
tigase.tests.utils.AccountBuilder.build(AccountBuilder.java:123)
tigase.tests.server.TestWebSocketConnectivity.setUp(TestWebSocketConnectivity.java:76)
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:132)
org.testng.internal.MethodInvocationHelper.invokeMethodConsideringTimeout(MethodInvocationHelper.java:61)
org.testng.internal.ConfigInvoker.invokeConfigurationMethod(ConfigInvoker.java:366)
org.testng.internal.ConfigInvoker.invokeConfigurations(ConfigInvoker.java:320)
org.testng.internal.TestInvoker.runConfigMethods(TestInvoker.java:701)
org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:527)
org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:174)
org.testng.internal.MethodRunner.runInSequence(MethodRunner.java:46)
org.testng.internal.TestInvoker$MethodInvocationAgent.invoke(TestInvoker.java:822)
org.testng.internal.TestInvoker.invokeTestMethods(TestInvoker.java:147)
org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:146)
org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:128)
java.base/java.util.ArrayList.forEach(ArrayList.java:1541)
org.testng.TestRunner.privateRun(TestRunner.java:764)
org.testng.TestRunner.run(TestRunner.java:585)
org.testng.SuiteRunner.runTest(SuiteRunner.java:384)
org.testng.SuiteRunner.runSequentially(SuiteRunner.java:378)
org.testng.SuiteRunner.privateRun(SuiteRunner.java:337)
org.testng.SuiteRunner.run(SuiteRunner.java:286)
org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:53)
org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:96)
org.testng.TestNG.runSuitesSequentially(TestNG.java:1218)
org.testng.TestNG.runSuitesLocally(TestNG.java:1140)
org.testng.TestNG.runSuites(TestNG.java:1069)
org.testng.TestNG.run(TestNG.java:1037)
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 Tests
tigase.tests.server.TestWebSocketConnectivity
testWebSocket_ConnectionHeaderCaseSensitivity	0.000s	2020-08-25 18:30:24 \| 2020-08-25 18:30:24 \| null / [TestClass name=class tigase.tests.server.TestWebSocketConnectivity] 2020-08-25 18:30:24 \| ------------------------------------
testWebSocket_StartTLSFeature	0.000s	2020-08-25 18:30:24 \| 2020-08-25 18:30:24 \| null / [TestClass name=class tigase.tests.server.TestWebSocketConnectivity] 2020-08-25 18:30:24 \| ------------------------------------
testWebSocket_TwoFramesPingAndTextFrameInSingleTcpFrame	0.000s	2020-08-25 18:30:24 \| 2020-08-25 18:30:24 \| Running: Tigase XMPP server monitoring - #2951 2020-08-25 18:30:24 \| ------------------------------------

WebSocket: Test WebSocket connectivity - #3305