******************************************************************************** Beginning Test: com/sun/ts/tests/websocket/ee/jakarta/websocket/session/WSClient.java#getProtocolVersionTest ******************************************************************************** Undeploying apps... StandardDeployment14.isDeployed() Deployed Modules: Search for s1as runtime files match:`ws_session_web.war` Valid runtime files after sweep: Deploying apps for forward rebuildable... Search for s1as runtime files match:`ws_session_web.war` Valid runtime files after sweep: StandardDeployment14.deploy() Starting to distribute: /home/ec2-user/jakartaee91tck/dist/com/sun/ts/tests/websocket/ee/jakarta/websocket/session/ws_session_web.war file: /home/ec2-user/jakartaee91tck/dist/com/sun/ts/tests/websocket/ee/jakarta/websocket/session/ws_session_web.war plan: java.io.ByteArrayInputStream@5931956e amd Obtained CACHED DeploymentManager amd Obtained CACHED DeploymentManager $$$$$$$$$$ Deployment SUCCEEDED for "/home/ec2-user/jakartaee91tck/dist/com/sun/ts/tests/websocket/ee/jakarta/websocket/session/ws_session_web.war" amd Obtained CACHED DeploymentManager amd Obtained CACHED DeploymentManager harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: harness.executeMode is set to "0" keywords (to be passed to tests) set to: all sClassPathFromExecProps = :: 03-13-2021 07:15:54: Harness - harness.executeMode is set to "0" 03-13-2021 07:15:54: Harness - keywords (to be passed to tests) set to: all 03-13-2021 07:15:54: Harness - sClassPathFromExecProps = :: command: com.sun.ts.lib.harness.ExecTSTestCmd CLASSPATH=:::/home/ec2-user/jakartaee91tck/lib/jaxb-api.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-core.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-impl.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-xjc.jar:/home/ec2-user/jakartaee91tck/lib/tsharness.jar:/home/ec2-user/jakartaee91tck/lib/cts.jar:/home/ec2-user/jakartaee91tck/lib/sigtest.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/javaee-api-8.0-5.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/tomee-tck-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/openejb-core-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/openejb-core-eclipselink-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/FastInfoset-1.2.16.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/activemq-client-5.16.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/activemq-jdbc-store-5.16.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/bval-jsr-2.0.5.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/catalina.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-cli-1.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-collections-3.2.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-collections4-4.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-dbcp2-2.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-lang3-3.11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-logging-1.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/commons-pool2-2.3.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/cts-9.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/dbprocedures-9.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/derby-10.14.2.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/derbyclient-10.14.2.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/derbynet-10.14.2.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/eclipselink-3.0.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/el-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/geronimo-connector-3.1.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/geronimo-javamail_1.6_mail-1.0.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/geronimo-jms_1.1_spec-1.1.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/geronimo-mail-2.0.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/geronimo-transaction-3.1.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/hawtbuf-1.11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/howl-1.0.1-1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/hsqldb-2.3.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/istack-commons-runtime-3.0.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jackson-annotations-2.12.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jackson-core-2.12.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jackson-databind-2.12.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jakarta.activation-1.2.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jakarta.xml.bind-api-2.3.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jaspic-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/javaee-api-8.0-5.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jaxb-runtime-2.3.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/jsp-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/mbean-annotation-api-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-api-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-core-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-core-eclipselink-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-javaagent.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-jee-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-jee-accessors-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-jpa-integration-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openejb-loader-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openjpa-3.1.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ee-2.0.22-20210313.025102-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ee-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ee-common-2.0.22-20210313.025019-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ee-common-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ejb-2.0.22-20210313.025052-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-ejb-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-el22-2.0.22-20210313.025030-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-el22-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-impl-2.0.22-20210313.025001-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-impl-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-spi-2.0.22-20210313.024943-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-spi-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-web-2.0.22-20210313.025042-11.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/openwebbeans-web-2.0.22-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/quartz-openejb-shade-2.2.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/serp-1.15.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/servlet-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/slf4j-api-1.7.21.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/stax-ex-1.8.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/swizzle-stream-1.6.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/sxc-jaxb-core-0.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/sxc-runtime-0.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomcat-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomcat-juli.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomcat-util-scan.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomcat-util.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomcat-websocket.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomee-catalina-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tomee-security-8.0.7-SNAPSHOT.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tsharness-9.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/tssv-9.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/txw2-2.3.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/websocket-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/whitebox-9.1.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-asm-util-4.18.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-asm7-shaded-4.14.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-asm8-shaded-4.17.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-asm9-shaded-4.18.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-bundleutils-4.14.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-finder-shaded-4.14.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-naming-4.14.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/lib/xbean-reflect-4.14.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/commons-logging-1.2.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/eclipselink-3.0.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/derby-10.14.2.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/derbyclient-10.14.2.0.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/openejb-client-8.0.7-SNAPSHOT.jar:/home/ec2-user/jakartaee91tck/lib/javatest.jar:/home/ec2-user/jakartaee91tck/lib/tsharness.jar:/home/ec2-user/jakartaee91tck/lib/cts.jar:/home/ec2-user/jakartaee91tck/lib/dbprocedures.jar:/home/ec2-user/jakartaee91tck/lib/commons-httpclient-3.1.jar:/home/ec2-user/jakartaee91tck/lib/jdom-1.1.3.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-api.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-impl.jar:/home/ec2-user/jakartaee91tck/lib/jaxb-xjc.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/jasper-el.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/cxf-rt-rs-client-3.3.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/cxf-rt-transports-http-3.3.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/cxf-core-3.3.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/woodstox-core-5.0.3.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/stax2-api-3.1.4.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/xmlschema-core-2.2.5.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/cxf-rt-frontend-jaxrs-3.3.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/cxf-rt-rs-sse-3.3.8.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/johnzon-core-1.2.10.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/johnzon-jaxrs-1.2.10.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/johnzon-jsonb-1.2.10.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/johnzon-jsonp-strict-1.2.10.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/johnzon-mapper-1.2.10.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-api.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-coyote.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-dbcp.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-cs.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-de.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-es.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-fr.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-ja.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-ko.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-pt-BR.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-ru.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-i18n-zh-CN.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-jdbc.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-jni.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-util-scan.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-util.jar:/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/lib/tomcat-websocket.jar:/home/ec2-user/jakartaee91tck/lib/javatest.jar:/home/ec2-user/jakartaee91tck/lib/jdom-1.1.3.jar:/lib/ant.jar:/home/ec2-user/glassfish6/glassfish/modules/admin-cli.jar:/home/ec2-user/jakartaee91tck/lib/http.jar:/home/ec2-user/jakartaee91tck/lib/glassfishporting.jar:/home/ec2-user/glassfish6/glassfish/modules/jakarta.activation-api.jar:/home/ec2-user/jakartaee91tck/classes:/home/ec2-user/jdk8/jre/../lib/tools.jar:/home/ec2-user/jakartaee91tck/lib/commons-httpclient-3.1.jar:/home/ec2-user/jakartaee91tck/lib/commons-logging-1.1.3.jar:/home/ec2-user/jakartaee91tck/lib/commons-codec-1.9.jar:/home/ec2-user/jakartaee91tck/lib/cssparser-0.9.25.jar:/home/ec2-user/jakartaee91tck/lib/htmlunit-2.15.jar:/home/ec2-user/jakartaee91tck/lib/htmlunit-core-js-2.15.jar:/home/ec2-user/jakartaee91tck/lib/httpclient-4.5.5.jar:/home/ec2-user/jakartaee91tck/lib/httpmime-4.5.5.jar:/home/ec2-user/jakartaee91tck/lib/httpcore-4.4.9.jar:/home/ec2-user/jakartaee91tck/lib/commons-collections-3.2.1.jar:/home/ec2-user/jakartaee91tck/lib/commons-io-2.4.jar:/home/ec2-user/jakartaee91tck/lib/commons-lang3-3.3.2.jar:/home/ec2-user/jakartaee91tck/lib/jaxen-1.1.6.jar:/home/ec2-user/jakartaee91tck/lib/jaxen-core-1.0.jar:/home/ec2-user/jakartaee91tck/lib/js-1.6R1.jar:/home/ec2-user/jakartaee91tck/lib/nekohtml-1.9.21.jar:/home/ec2-user/jakartaee91tck/lib/sac-1.3.jar:/home/ec2-user/jakartaee91tck/lib/saxpath.jar:/home/ec2-user/jakartaee91tck/lib/xercesImpl-2.11.0.jar:/home/ec2-user/jakartaee91tck/lib/xalan-2.7.2.jar:/home/ec2-user/jakartaee91tck/lib/tssv.jar:/home/ec2-user/jakartaee91tck/lib/xml-apis-1.4.01.jar DISPLAY=:0.0 HOME=/home/ec2-user windir= SYSTEMROOT= PATH=/home/ec2-user/glassfish6/glassfish/nativelib /home/ec2-user/jdk8/jre/bin/java -Dcts.tmp=/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/harnesstmp/ -Djava.protocol.handler.pkgs=javax.net.ssl -Djavax.net.ssl.keyStore=/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/src/test/keystores/clientcert.jks -Djavax.net.ssl.keyStorePassword=changeit -Djavax.net.ssl.trustStore=/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/src/test/keystores/ssl-truststore -Djavax.net.ssl.trustStorePassword=changeit -Djava.endorsed.dirs=/home/ec2-user/glassfish6/glassfish/modules/endorsed:/home/ec2-user/jakartaee91tck/endorsedlib -Dcom.sun.aas.installRoot=/home/ec2-user/glassfish6/glassfish -Dcatalina.base=target/apache-tomee-plume-9.0.0-M5-SNAPSHOT -Dlog.file.location=/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/logs -Dservlet.is.jsr115.compatible=false -Dprovider.configuration.file=/home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/apache-tomee-plume-9.0.0-M5-SNAPSHOT/conf/ProviderConfiguration.xml -Dlogical.hostname.servlet=Catalina/localhost -Dcom.sun.aas.configRoot=/home/ec2-user/glassfish6/glassfish/config -Ddeliverable.class=com.sun.ts.lib.deliverable.cts.CTSDeliverable -Dopenejb.validation.output.level=3 com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient -p /home/ec2-user/gbuild-aws/node/target/apache-tomee/temp/temp9049402495638448259dir/gbuild/work/workspace-1/project-8189/target/harnesstmp//tmp/tstest.jte -t getProtocolVersionTest ************************************************************ * props file set to "/tmp/ec2-user-cts-props.txt" ************************************************************ 03-13-2021 07:15:54: TRACE: ####### Value of harness.socket.retry.count is "10" 03-13-2021 07:15:54: TRACE: ####### Value of harness.log.port is "44019" 03-13-2021 07:15:55: TRACE: ####### Actual bind value of harness.log.port is "44019" 03-13-2021 07:15:55: TRACE: in custom vehicle so call on EETest. 03-13-2021 07:15:55: TRACE: *** in EETest.run(argv,p) 03-13-2021 07:15:55: TRACE: TESTCLASS=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:55: TRACE: ** IN getRunMethod: testClass=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:55: TRACE: ** IN getRunMethod: testname=getProtocolVersionTest 03-13-2021 07:15:55: TRACE: ** GOT RUN METHOD! 03-13-2021 07:15:55: TRACE: **runmethod=getProtocolVersionTest 03-13-2021 07:15:55: TRACE: ABOUT TO GET SETUP METHOD! 03-13-2021 07:15:55: TRACE: No setupMethod annotation present 03-13-2021 07:15:55: TRACE: getSetupMethod - checking for testcase specific setup method: getProtocolVersionTest_setup 03-13-2021 07:15:55: TRACE: getSetupMethod - checking for default class specific setup method 03-13-2021 07:15:55: TRACE: GOT SETUP METHOD! 03-13-2021 07:15:55: TRACE: No cleanupMethod annotation present 03-13-2021 07:15:55: TRACE: getCleanupMethod - checking for testcase specific cleanup method: getProtocolVersionTest_cleanup 03-13-2021 07:15:55: TRACE: getCleanupMethod - checking for default class specific cleanup method 03-13-2021 07:15:55: TRACE: GOT CLEANUP METHOD! 03-13-2021 07:15:55: TRACE: ABOUT TO INVOKE SETUP METHOD! 03-13-2021 07:15:55: TRACE: setup method WebSocketCommonClient 03-13-2021 07:15:55: [WebSocketCommonClient] Test setup OK 03-13-2021 07:15:55: TRACE: INVOKED SETUP METHOD! 03-13-2021 07:15:55: TRACE: ABOUT TO INVOKE EETEST RUN METHOD! 03-13-2021 07:15:55: TRACE: Reseting callback and message 03-13-2021 07:15:55: TRACE: [WebSocketCommonClient] A new test case has been created 03-13-2021 07:15:55: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:55: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:55: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:55: [WebSocketCommonClient] Connecting to ws://localhost:46851/ws_session_web/TCKTestServer 03-13-2021 07:15:55: TRACE: On open on session id 0 03-13-2021 07:15:55: TRACE: RequestUri: NULL 03-13-2021 07:15:56: TRACE: Received message so far ========TCKTestServer opened 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:56: TRACE: Received message so far ========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] Connection session id: 0 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:56: [WebSocketCommonClient] Response: ========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '8' in the server's response 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 28 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '36' in the server's response 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:56: getProtocolVersion() is 13 as expected 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:56: Sending entity 1 part: testName=getProtocolVersionTest, TCKTestServer got String message: testName=getProtocolVersionTest ========TCKTestServer received String:testName=getProtocolVersionTest 03-13-2021 07:15:56: TRACE: RemoteEndpoint.Basic.sendText( testName=getProtocolVersionTest ) 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:56: TRACE: Received message so far ========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:56: TRACE: Received message so far ========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:56: [WebSocketCommonClient] Response: ========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '8' in the server's response 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 28 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '36' in the server's response 03-13-2021 07:15:56: [WebSocketCommonClient] Response: ========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer received String:'... 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer received String:' at index '76' in the server's response 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'testName=getProtocolVersionTest'... 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'testName=getProtocolVersionTest' at index '107' in the server's response 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer: getProtocolVersion returned=13'... 03-13-2021 07:15:56: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer: getProtocolVersion returned=13' at index '146' in the server's response 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] session.close() on session id 0 java.lang.IllegalStateException: Message will not be sent because the WebSocket session has been closed at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:439) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192) at org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37) at com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSTestServer.onClose(WSTestServer.java:149) at sun.reflect.GeneratedMethodAccessor76.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.tomcat.websocket.pojo.PojoEndpointBase.onClose(PojoEndpointBase.java:103) at org.apache.tomcat.websocket.WsSession.fireEndpointOnClose(WsSession.java:556) at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:534) at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367) at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296) at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133) at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85) at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183) at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162) at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:156) at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:887) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1684) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:748) ==From WSTestServer onClose(Session)== 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] On close on session id 0 reason CloseReason: code [1000], reason [] 03-13-2021 07:15:56: TRACE: [WebSocketCommonClient] onClose has been called 03-13-2021 07:15:56: [WebSocketCommonClient] Test cleanup OK 03-13-2021 07:15:56: [WebSocketCommonClient] Test cleanup OK 03-13-2021 07:15:57: TRACE: SLEPT FOR: 1000 03-13-2021 07:15:57: TRACE: in custom vehicle so call on EETest. 03-13-2021 07:15:57: TRACE: *** in EETest.run(argv,p) 03-13-2021 07:15:57: TRACE: TESTCLASS=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:57: TRACE: ** IN getRunMethod: testClass=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:57: TRACE: ** IN getRunMethod: testname=getProtocolVersionTest 03-13-2021 07:15:57: TRACE: ** GOT RUN METHOD! 03-13-2021 07:15:57: TRACE: **runmethod=getProtocolVersionTest 03-13-2021 07:15:57: TRACE: ABOUT TO GET SETUP METHOD! 03-13-2021 07:15:57: TRACE: No setupMethod annotation present 03-13-2021 07:15:57: TRACE: getSetupMethod - checking for testcase specific setup method: getProtocolVersionTest_setup 03-13-2021 07:15:57: TRACE: getSetupMethod - checking for default class specific setup method 03-13-2021 07:15:57: TRACE: GOT SETUP METHOD! 03-13-2021 07:15:57: TRACE: No cleanupMethod annotation present 03-13-2021 07:15:57: TRACE: getCleanupMethod - checking for testcase specific cleanup method: getProtocolVersionTest_cleanup 03-13-2021 07:15:57: TRACE: getCleanupMethod - checking for default class specific cleanup method 03-13-2021 07:15:57: TRACE: GOT CLEANUP METHOD! 03-13-2021 07:15:57: TRACE: ABOUT TO INVOKE SETUP METHOD! 03-13-2021 07:15:57: TRACE: setup method WebSocketCommonClient 03-13-2021 07:15:57: [WebSocketCommonClient] Test setup OK 03-13-2021 07:15:57: TRACE: INVOKED SETUP METHOD! 03-13-2021 07:15:57: TRACE: ABOUT TO INVOKE EETEST RUN METHOD! 03-13-2021 07:15:57: TRACE: Reseting callback and message 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] A new test case has been created 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:57: [WebSocketCommonClient] Connecting to ws://localhost:46851/ws_session_web/TCKTestServer 03-13-2021 07:15:57: TRACE: On open on session id 1 03-13-2021 07:15:57: TRACE: RequestUri: NULL 1 03-13-2021 07:15:57: TRACE: Received message so far ======Another SessionID=1========TCKTestServer opened TCKTestServer got String message: testName=getProtocolVersionTest ========TCKTestServer received String:testName=getProtocolVersionTest 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:57: TRACE: Received message so far ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] Connection session id: 1 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:57: [WebSocketCommonClient] Response: ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '33' in the server's response 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 53 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '61' in the server's response 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:57: getProtocolVersion() is 13 as expected 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:57: Sending entity 1 part: testName=getProtocolVersionTest, 03-13-2021 07:15:57: TRACE: Received message so far ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:57: TRACE: RemoteEndpoint.Basic.sendText( testName=getProtocolVersionTest ) 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:57: TRACE: Received message so far ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:57: [WebSocketCommonClient] Response: ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '33' in the server's response 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 53 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '61' in the server's response 03-13-2021 07:15:57: [WebSocketCommonClient] Response: ======Another SessionID=1========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer received String:'... 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer received String:' at index '101' in the server's response 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'testName=getProtocolVersionTest'... 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'testName=getProtocolVersionTest' at index '132' in the server's response 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer: getProtocolVersion returned=13'... 03-13-2021 07:15:57: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer: getProtocolVersion returned=13' at index '171' in the server's response 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] session.close() on session id 1 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] On close on session id 1 reason CloseReason: code [1000], reason [] 03-13-2021 07:15:57: TRACE: [WebSocketCommonClient] onClose has been called 03-13-2021 07:15:57: [WebSocketCommonClient] Test cleanup OK 03-13-2021 07:15:57: [WebSocketCommonClient] Test cleanup OK java.lang.IllegalStateException: Message will not be sent because the WebSocket session has been closed at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:439) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192) at org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37) at com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSTestServer.onClose(WSTestServer.java:149) at sun.reflect.GeneratedMethodAccessor76.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.tomcat.websocket.pojo.PojoEndpointBase.onClose(PojoEndpointBase.java:103) at org.apache.tomcat.websocket.WsSession.fireEndpointOnClose(WsSession.java:556) at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:534) at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367) at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296) at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133) at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85) at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183) at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162) at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:156) at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:887) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1684) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:748) ==From WSTestServer onClose(Session)== 03-13-2021 07:15:58: TRACE: SLEPT FOR: 1000 03-13-2021 07:15:58: TRACE: in custom vehicle so call on EETest. 03-13-2021 07:15:58: TRACE: *** in EETest.run(argv,p) 03-13-2021 07:15:58: TRACE: TESTCLASS=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:58: TRACE: ** IN getRunMethod: testClass=com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSClient 03-13-2021 07:15:58: TRACE: ** IN getRunMethod: testname=getProtocolVersionTest 03-13-2021 07:15:58: TRACE: ** GOT RUN METHOD! 03-13-2021 07:15:58: TRACE: **runmethod=getProtocolVersionTest 03-13-2021 07:15:58: TRACE: ABOUT TO GET SETUP METHOD! 03-13-2021 07:15:58: TRACE: No setupMethod annotation present 03-13-2021 07:15:58: TRACE: getSetupMethod - checking for testcase specific setup method: getProtocolVersionTest_setup 03-13-2021 07:15:58: TRACE: getSetupMethod - checking for default class specific setup method 03-13-2021 07:15:58: TRACE: GOT SETUP METHOD! 03-13-2021 07:15:58: TRACE: No cleanupMethod annotation present 03-13-2021 07:15:58: TRACE: getCleanupMethod - checking for testcase specific cleanup method: getProtocolVersionTest_cleanup 03-13-2021 07:15:58: TRACE: getCleanupMethod - checking for default class specific cleanup method 03-13-2021 07:15:58: TRACE: GOT CLEANUP METHOD! 03-13-2021 07:15:58: TRACE: ABOUT TO INVOKE SETUP METHOD! 03-13-2021 07:15:58: TRACE: setup method WebSocketCommonClient 03-13-2021 07:15:58: [WebSocketCommonClient] Test setup OK 03-13-2021 07:15:58: TRACE: INVOKED SETUP METHOD! 03-13-2021 07:15:58: TRACE: ABOUT TO INVOKE EETEST RUN METHOD! 03-13-2021 07:15:58: TRACE: Reseting callback and message 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] A new test case has been created 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:58: [WebSocketCommonClient] Connecting to ws://localhost:46851/ws_session_web/TCKTestServer 03-13-2021 07:15:58: TRACE: On open on session id 2 03-13-2021 07:15:58: TRACE: RequestUri: NULL 2 03-13-2021 07:15:58: TRACE: Received message so far ======Another SessionID=2========TCKTestServer opened 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:58: TRACE: Received message so far ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] Connection session id: 2 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:58: [WebSocketCommonClient] Response: ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '33' in the server's response 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 53 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '61' in the server's response 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:58: getProtocolVersion() is 13 as expected 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] invoke 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] EXECUTING 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] setTestProperties 03-13-2021 07:15:58: Sending entity 1 part: testName=getProtocolVersionTest, TCKTestServer got String message: testName=getProtocolVersionTest ========TCKTestServer received String:testName=getProtocolVersionTest 03-13-2021 07:15:58: TRACE: Received message so far ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:58: TRACE: Received message so far ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch hit 03-13-2021 07:15:58: TRACE: RemoteEndpoint.Basic.sendText( testName=getProtocolVersionTest ) 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] Setting CountDownLatch to 5 seconds, should be hit 2 times 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Expected Status Code '200' found in response line! 03-13-2021 07:15:58: [WebSocketCommonClient] Response: ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer opened' starting at index location: 0 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer opened' at index '33' in the server's response 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'session from Server is open=TRUE' starting at index location: 53 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'session from Server is open=TRUE' at index '61' in the server's response 03-13-2021 07:15:58: [WebSocketCommonClient] Response: ======Another SessionID=2========TCKTestServer opened========session from Server is open=TRUE========TCKTestServer received String: testName=getProtocolVersionTest========TCKTestServer: getProtocolVersion returned=13 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer received String:'... 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer received String:' at index '101' in the server's response 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'testName=getProtocolVersionTest'... 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'testName=getProtocolVersionTest' at index '132' in the server's response 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Scanning response for search string: 'TCKTestServer: getProtocolVersion returned=13'... 03-13-2021 07:15:58: TRACE: [WebValidatorBase] Found search string: 'TCKTestServer: getProtocolVersion returned=13' at index '171' in the server's response 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] CountDownLatch has been hit 2 times as expected 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] session.close() on session id 2 ==From WSTestServer onClose(Session)== java.lang.IllegalStateException: Message will not be sent because the WebSocket session has been closed at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.writeMessagePart(WsRemoteEndpointImplBase.java:439) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:311) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendMessageBlock(WsRemoteEndpointImplBase.java:251) at org.apache.tomcat.websocket.WsRemoteEndpointImplBase.sendString(WsRemoteEndpointImplBase.java:192) at org.apache.tomcat.websocket.WsRemoteEndpointBasic.sendText(WsRemoteEndpointBasic.java:37) at com.sun.ts.tests.websocket.ee.jakarta.websocket.session.WSTestServer.onClose(WSTestServer.java:149) at sun.reflect.GeneratedMethodAccessor76.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.apache.tomcat.websocket.pojo.PojoEndpointBase.onClose(PojoEndpointBase.java:103) at org.apache.tomcat.websocket.WsSession.fireEndpointOnClose(WsSession.java:556) at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:534) at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367) at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296) at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133) at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85) at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183) at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162) at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:156) at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60) at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59) at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:887) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1684) at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Thread.java:748) 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] On close on session id 2 reason CloseReason: code [1000], reason [] 03-13-2021 07:15:58: TRACE: [WebSocketCommonClient] onClose has been called 03-13-2021 07:15:58: [WebSocketCommonClient] Test cleanup OK 03-13-2021 07:15:58: [WebSocketCommonClient] Test cleanup OK 03-13-2021 07:15:59: TRACE: SLEPT FOR: 1000 STATUS:Passed. Passed. ******************************************************************************** Finished Test: PASSED........com/sun/ts/tests/websocket/ee/jakarta/websocket/session/WSClient.java#getProtocolVersionTest ********************************************************************************