[TOMEE-3226] websocket.spec.servercontainer.addendpoint setTimeout1Test

• Type: Sub-task
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Fix Version/s: 9.0.0-M7
• Assignee: Unassigned
• Reporter: David Blevins
• Created: 11/Apr/21 01:59
• Updated: 16/May/21 23:01
• Resolved: 03/May/21 09:19

Description

Follow setup instructions in TOMEE-3140. Then run:

./runtests --ee91 -c -j --web tomee-plume com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient#setTimeout1Test

At the time this issue was filed, the test failed with the following in target/logs/javatest.log

[MessageValidator] Scanning for search string: 'TCKTestServer received String: testName=setTimeout1Test' starting at index location: 91
[MessageValidator] Found search string: 'TCKTestServer received String: testName=setTimeout1Test' at index '99
Test failed due to message sent and/or received after timeout from client side
04-12-2021 03:07:55:  ERROR: Test failed with incorrect response
com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.setTimeout1Test(WSClient.java:1416)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.sun.ts.lib.harness.EETest.run(EETest.java:596)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:115)
	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:486)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:209)
	at com.sun.ts.tests.websocket.common.client.WebSocketCommonClient.run(WebSocketCommonClient.java:570)
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.main(WSClient.java:60)
04-12-2021 03:07:55:  ERROR: Test case throws exception: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
04-12-2021 03:07:55:  ERROR: Exception at:  
04-12-2021 03:07:55:  ERROR: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.setTimeout1Test(WSClient.java:1416)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.sun.ts.lib.harness.EETest.run(EETest.java:596)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:115)
	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:486)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:209)
	at com.sun.ts.tests.websocket.common.client.WebSocketCommonClient.run(WebSocketCommonClient.java:570)
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.main(WSClient.java:60)

04-12-2021 03:07:55:  [WebSocketCommonClient] Test cleanup OK
04-12-2021 03:07:56:  TRACE: SLEPT FOR:  1000
04-12-2021 03:07:56:  TRACE: in custom vehicle so call on EETest.
04-12-2021 03:07:56:  TRACE: *** in EETest.run(argv,p)
04-12-2021 03:07:56:  TRACE: TESTCLASS=com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient
04-12-2021 03:07:56:  TRACE: ** IN getRunMethod: testClass=com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient
04-12-2021 03:07:56:  TRACE: ** IN getRunMethod: testname=setTimeout1Test
04-12-2021 03:07:56:  TRACE: ** GOT RUN METHOD!
04-12-2021 03:07:56:  TRACE: **runmethod=setTimeout1Test
04-12-2021 03:07:56:  TRACE: ABOUT TO GET SETUP METHOD!
04-12-2021 03:07:56:  TRACE: No setupMethod annotation present
04-12-2021 03:07:56:  TRACE: getSetupMethod - checking for testcase specific setup method:  setTimeout1Test_setup
04-12-2021 03:07:56:  TRACE: getSetupMethod - checking for default class specific setup method
04-12-2021 03:07:56:  TRACE: GOT SETUP METHOD!
04-12-2021 03:07:56:  TRACE: No cleanupMethod annotation present
04-12-2021 03:07:56:  TRACE: getCleanupMethod - checking for testcase specific cleanup method:  setTimeout1Test_cleanup
04-12-2021 03:07:56:  TRACE: getCleanupMethod - checking for default class specific cleanup method
04-12-2021 03:07:56:  TRACE: GOT CLEANUP METHOD!
04-12-2021 03:07:56:  TRACE: ABOUT TO INVOKE SETUP METHOD!
04-12-2021 03:07:56:  TRACE: setup method WebSocketCommonClient
04-12-2021 03:07:56:  [WebSocketCommonClient] Test setup OK
04-12-2021 03:07:56:  TRACE: INVOKED SETUP METHOD!
04-12-2021 03:07:56:  TRACE: ABOUT TO INVOKE EETEST RUN METHOD!
getMaxIdleTimeout returned default value on client side =0
TCKTestServer got String message: testName=setTimeout1Test
========In setMaxIdleTimeout1Test: TCKTestServer received String:testName=setTimeout1Test
TCKBasicEndpoint OnOpen========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=setTimeout1Test========TCKTestServer default timeout: 0TCKBasicEndpoint OnClose CloseCode=CLOSED_ABNORMALLYTCKBasicEndpoint OnClose ReasonPhrase=The WebSocket session [0] idle timeout expiredTCKBasicEndpoint OnOpen========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=setTimeout1Test========TCKTestServer default timeout: 0========TCKTestServer second message after sleep 40 secondTCKBasicEndpoint OnClose CloseCode=CLOSED_ABNORMALLYTCKBasicEndpoint OnClose ReasonPhrase=The WebSocket session [1] idle timeout expiredTCKBasicEndpoint OnOpen========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=setTimeout1Test========TCKTestServer default timeout: 0TCKBasicEndpoint OnClose CloseCode=CLOSED_ABNORMALLYTCKBasicEndpoint OnClose ReasonPhrase=The WebSocket session [2] idle timeout expired
[MessageValidator] Scanning for search string: 'TCKBasicEndpoint OnOpen' starting at index location: 0
[MessageValidator] Found search string: 'TCKBasicEndpoint OnOpen' at index '0
[MessageValidator] Scanning for search string: 'TCKTestServer opened' starting at index location: 23
[MessageValidator] Found search string: 'TCKTestServer opened' at index '31
[MessageValidator] Scanning for search string: 'session from Server is open=TRUE' starting at index location: 51
Test failed due to message sent and/or received after timeout from client side
[MessageValidator] Found search string: 'session from Server is open=TRUE' at index '59
[MessageValidator] Scanning for search string: 'TCKTestServer received String: testName=setTimeout1Test' starting at index location: 91
[MessageValidator] Found search string: 'TCKTestServer received String: testName=setTimeout1Test' at index '99
04-12-2021 03:38:24:  ERROR: Test failed with incorrect response
com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.setTimeout1Test(WSClient.java:1416)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.sun.ts.lib.harness.EETest.run(EETest.java:596)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:115)
	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:486)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:209)
	at com.sun.ts.tests.websocket.common.client.WebSocketCommonClient.run(WebSocketCommonClient.java:570)
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.main(WSClient.java:60)
04-12-2021 03:38:24:  ERROR: Test case throws exception: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
04-12-2021 03:38:24:  ERROR: Exception at:  
04-12-2021 03:38:24:  ERROR: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.setTimeout1Test(WSClient.java:1416)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.sun.ts.lib.harness.EETest.run(EETest.java:596)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:115)
	at com.sun.ts.lib.harness.EETest.getPropsReady(EETest.java:486)
	at com.sun.ts.lib.harness.ServiceEETest.run(ServiceEETest.java:209)
	at com.sun.ts.tests.websocket.common.client.WebSocketCommonClient.run(WebSocketCommonClient.java:570)
	at com.sun.ts.tests.websocket.spec.servercontainer.addendpoint.WSClient.main(WSClient.java:60)

04-12-2021 03:38:24:  [WebSocketCommonClient] Test cleanup OK
04-12-2021 03:38:25:  TRACE: SLEPT FOR:  1000
STATUS:Failed.Test case throws exception: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
Failed. Test case throws exception: com.sun.ts.lib.harness.EETest$Fault: Test failed with incorrect response
********************************************************************************
Finished Test:  FAILED........com/sun/ts/tests/websocket/spec/servercontainer/addendpoint/WSClient.java#setTimeout1Test

For tips on debugging/fixing tests, see https://github.com/apache/tomee-tck/blob/master/DEBUGGING.adoc

If you have any thoughts about what the test is expecting/testing, please add them to the comments.