[2025-07-02T14:17:57.457Z] Running test jdk_net_0 ... [2025-07-02T14:17:57.458Z] =============================================== [2025-07-02T14:17:57.458Z] jdk_net_0 Start Time: Wed Jul 2 14:17:55 2025 Epoch Time (ms): 1751465875879 [2025-07-02T14:17:57.458Z] variation: Mode150 [2025-07-02T14:17:57.458Z] JVM_OPTIONS: -XX:+UseCompressedOops [2025-07-02T14:17:57.458Z] { \ [2025-07-02T14:17:57.458Z] echo ""; echo "TEST SETUP:"; \ [2025-07-02T14:17:57.458Z] echo "Nothing to be done for setup."; \ [2025-07-02T14:17:57.458Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0"; \ [2025-07-02T14:17:57.458Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0"; \ [2025-07-02T14:17:57.458Z] echo ""; echo "TESTING:"; \ [2025-07-02T14:17:57.458Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-07-02T14:17:57.458Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:1 -nativepath:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native" -vmoptions:"-Xmx512m -XX:+UseCompressedOops " \ [2025-07-02T14:17:57.458Z] -w ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0"/work" \ [2025-07-02T14:17:57.458Z] -r ""/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0"/report" \ [2025-07-02T14:17:57.458Z] -jdk:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image" \ [2025-07-02T14:17:57.458Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk/ProblemList.txt" \ [2025-07-02T14:17:57.458Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk17.txt" \ [2025-07-02T14:17:57.458Z] \ [2025-07-02T14:17:57.458Z] -exclude:"/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk17.txt" \ [2025-07-02T14:17:57.458Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk:jdk_net"; \ [2025-07-02T14:17:57.458Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "jdk_net_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "jdk_net_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-02T14:17:57.458Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-02T14:17:57.458Z] echo "Nothing to be done for teardown."; \ [2025-07-02T14:17:57.458Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/TestTargetResult"; [2025-07-02T14:17:57.458Z] [2025-07-02T14:17:57.458Z] TEST SETUP: [2025-07-02T14:17:57.458Z] Nothing to be done for setup. [2025-07-02T14:17:57.458Z] [2025-07-02T14:17:57.458Z] TESTING: [2025-07-02T14:17:57.458Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0/work" not found: creating [2025-07-02T14:17:57.458Z] Directory "/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/../TKG/output_17514651618206/jdk_net_0/report" not found: creating [2025-07-02T14:18:01.866Z] XML output with verification to /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work Calling Pipeline was cancelled [2025-07-02T14:35:08.744Z] Sending interrupt signal to process [2025-07-02T14:35:21.921Z] -------------------------------------------------- [2025-07-02T14:35:21.921Z] TEST: java/net/httpclient/websocket/Abort.java [2025-07-02T14:35:21.921Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image [2025-07-02T14:35:21.921Z] [2025-07-02T14:35:21.921Z] ACTION: build -- Passed. Build successful [2025-07-02T14:35:21.921Z] REASON: User specified action: run build DummyWebSocketServer [2025-07-02T14:35:21.921Z] TIME: 0.233 seconds [2025-07-02T14:35:21.921Z] messages: [2025-07-02T14:35:21.921Z] command: build DummyWebSocketServer [2025-07-02T14:35:21.921Z] reason: User specified action: run build DummyWebSocketServer [2025-07-02T14:35:21.921Z] started: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.921Z] Test directory: [2025-07-02T14:35:21.921Z] compile: DummyWebSocketServer [2025-07-02T14:35:21.921Z] finished: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.921Z] elapsed time (seconds): 0.233 [2025-07-02T14:35:21.921Z] [2025-07-02T14:35:21.921Z] ACTION: compile -- Passed. Compilation successful [2025-07-02T14:35:21.921Z] REASON: .class file out of date or does not exist [2025-07-02T14:35:21.921Z] TIME: 0.233 seconds [2025-07-02T14:35:21.921Z] messages: [2025-07-02T14:35:21.921Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/DummyWebSocketServer.java [2025-07-02T14:35:21.921Z] reason: .class file out of date or does not exist [2025-07-02T14:35:21.921Z] started: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.921Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-07-02T14:35:21.921Z] Mode: agentvm [2025-07-02T14:35:21.921Z] Agent id: 1 [2025-07-02T14:35:21.921Z] finished: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.921Z] elapsed time (seconds): 0.233 [2025-07-02T14:35:21.921Z] configuration: [2025-07-02T14:35:21.921Z] Boot Layer (javac runtime environment) [2025-07-02T14:35:21.921Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-07-02T14:35:21.921Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/patches/java.base [2025-07-02T14:35:21.921Z] [2025-07-02T14:35:21.921Z] javac compilation environment [2025-07-02T14:35:21.921Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-07-02T14:35:21.921Z] add exports: java.base/sun.net ALL-UNNAMED [2025-07-02T14:35:21.921Z] java.base/sun.net.www ALL-UNNAMED [2025-07-02T14:35:21.921Z] java.base/sun.net.www.http ALL-UNNAMED [2025-07-02T14:35:21.921Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-07-02T14:35:21.921Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-07-02T14:35:21.921Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-07-02T14:35:21.921Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.921Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-07-02T14:35:21.921Z] [2025-07-02T14:35:21.921Z] rerun: [2025-07-02T14:35:21.921Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/scratch && \ [2025-07-02T14:35:21.921Z] DISPLAY=unix:0 \ [2025-07-02T14:35:21.921Z] HOME=/home/jenkins \ [2025-07-02T14:35:21.921Z] LANG=en_US \ [2025-07-02T14:35:21.921Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:21.921Z] TZ=Etc/UTC \ [2025-07-02T14:35:21.921Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image/bin/javac \ [2025-07-02T14:35:21.921Z] -J-ea \ [2025-07-02T14:35:21.921Z] -J-esa \ [2025-07-02T14:35:21.921Z] -J-Xmx512m \ [2025-07-02T14:35:21.921Z] -J-XX:+UseCompressedOops \ [2025-07-02T14:35:21.921Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ [2025-07-02T14:35:21.921Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ [2025-07-02T14:35:21.921Z] -J-Dtest.compiler.opts= \ [2025-07-02T14:35:21.921Z] -J-Dtest.java.opts= \ [2025-07-02T14:35:21.921Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.921Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.921Z] -J-Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:21.921Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-07-02T14:35:21.921Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-07-02T14:35:21.921Z] -J-Dtest.name=java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.921Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.921Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.921Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.921Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.921Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.921Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -J-Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-07-02T14:35:21.922Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.922Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/DummyWebSocketServer.java [2025-07-02T14:35:21.922Z] [2025-07-02T14:35:21.922Z] ACTION: build -- Passed. Build successful [2025-07-02T14:35:21.922Z] REASON: Named class compiled on demand [2025-07-02T14:35:21.922Z] TIME: 0.24 seconds [2025-07-02T14:35:21.922Z] messages: [2025-07-02T14:35:21.922Z] command: build Abort [2025-07-02T14:35:21.922Z] reason: Named class compiled on demand [2025-07-02T14:35:21.922Z] started: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.922Z] Test directory: [2025-07-02T14:35:21.922Z] compile: Abort [2025-07-02T14:35:21.922Z] finished: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.922Z] elapsed time (seconds): 0.24 [2025-07-02T14:35:21.922Z] [2025-07-02T14:35:21.922Z] ACTION: compile -- Passed. Compilation successful [2025-07-02T14:35:21.922Z] REASON: .class file out of date or does not exist [2025-07-02T14:35:21.922Z] TIME: 0.24 seconds [2025-07-02T14:35:21.922Z] messages: [2025-07-02T14:35:21.922Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/Abort.java [2025-07-02T14:35:21.922Z] reason: .class file out of date or does not exist [2025-07-02T14:35:21.922Z] started: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.922Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-07-02T14:35:21.922Z] Mode: agentvm [2025-07-02T14:35:21.922Z] Agent id: 1 [2025-07-02T14:35:21.922Z] finished: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.922Z] elapsed time (seconds): 0.24 [2025-07-02T14:35:21.922Z] configuration: [2025-07-02T14:35:21.922Z] Boot Layer (javac runtime environment) [2025-07-02T14:35:21.922Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-07-02T14:35:21.922Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/patches/java.base [2025-07-02T14:35:21.922Z] [2025-07-02T14:35:21.922Z] javac compilation environment [2025-07-02T14:35:21.922Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-07-02T14:35:21.922Z] add exports: java.base/sun.net ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.base/sun.net.www ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.base/sun.net.www.http ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-07-02T14:35:21.922Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.922Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-07-02T14:35:21.922Z] [2025-07-02T14:35:21.922Z] rerun: [2025-07-02T14:35:21.922Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/scratch && \ [2025-07-02T14:35:21.922Z] DISPLAY=unix:0 \ [2025-07-02T14:35:21.922Z] HOME=/home/jenkins \ [2025-07-02T14:35:21.922Z] LANG=en_US \ [2025-07-02T14:35:21.922Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:21.922Z] TZ=Etc/UTC \ [2025-07-02T14:35:21.922Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image/bin/javac \ [2025-07-02T14:35:21.922Z] -J-ea \ [2025-07-02T14:35:21.922Z] -J-esa \ [2025-07-02T14:35:21.922Z] -J-Xmx512m \ [2025-07-02T14:35:21.922Z] -J-XX:+UseCompressedOops \ [2025-07-02T14:35:21.922Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ [2025-07-02T14:35:21.922Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ [2025-07-02T14:35:21.922Z] -J-Dtest.compiler.opts= \ [2025-07-02T14:35:21.922Z] -J-Dtest.java.opts= \ [2025-07-02T14:35:21.922Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.922Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.922Z] -J-Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:21.922Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-07-02T14:35:21.922Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-07-02T14:35:21.922Z] -J-Dtest.name=java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.922Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.922Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.922Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.922Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -J-Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-07-02T14:35:21.922Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-07-02T14:35:21.922Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.922Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.922Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/Abort.java [2025-07-02T14:35:21.922Z] [2025-07-02T14:35:21.922Z] ACTION: testng -- Failed. Unexpected exit from test [exit code: 143] [2025-07-02T14:35:21.922Z] REASON: User specified action: run testng/othervm -Djdk.internal.httpclient.websocket.debug=true Abort [2025-07-02T14:35:21.922Z] TIME: 29.453 seconds [2025-07-02T14:35:21.922Z] messages: [2025-07-02T14:35:21.922Z] command: testng -Djdk.internal.httpclient.websocket.debug=true Abort [2025-07-02T14:35:21.922Z] reason: User specified action: run testng/othervm -Djdk.internal.httpclient.websocket.debug=true Abort [2025-07-02T14:35:21.922Z] started: Wed Jul 02 14:34:40 UTC 2025 [2025-07-02T14:35:21.922Z] Mode: othervm [/othervm specified] [2025-07-02T14:35:21.922Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-07-02T14:35:21.922Z] finished: Wed Jul 02 14:35:10 UTC 2025 [2025-07-02T14:35:21.922Z] elapsed time (seconds): 29.453 [2025-07-02T14:35:21.922Z] configuration: [2025-07-02T14:35:21.922Z] Boot Layer [2025-07-02T14:35:21.922Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-07-02T14:35:21.922Z] add exports: java.base/sun.net ALL-UNNAMED [2025-07-02T14:35:21.922Z] java.base/sun.net.www ALL-UNNAMED [2025-07-02T14:35:21.923Z] java.base/sun.net.www.http ALL-UNNAMED [2025-07-02T14:35:21.923Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-07-02T14:35:21.923Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-07-02T14:35:21.923Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-07-02T14:35:21.923Z] [2025-07-02T14:35:21.923Z] STDOUT: [2025-07-02T14:35:21.923Z] iteration #0 [2025-07-02T14:35:21.923Z] abort #0 [2025-07-02T14:35:21.923Z] abort #1 [2025-07-02T14:35:21.923Z] abort #2 [2025-07-02T14:35:21.923Z] request #0 [2025-07-02T14:35:21.923Z] request #1 [2025-07-02T14:35:21.923Z] request #2 [2025-07-02T14:35:21.923Z] iteration #1 [2025-07-02T14:35:21.923Z] abort #0 [2025-07-02T14:35:21.923Z] abort #1 [2025-07-02T14:35:21.923Z] abort #2 [2025-07-02T14:35:21.923Z] request #0 [2025-07-02T14:35:21.923Z] request #1 [2025-07-02T14:35:21.923Z] request #2 [2025-07-02T14:35:21.923Z] iteration #2 [2025-07-02T14:35:21.923Z] abort #0 [2025-07-02T14:35:21.923Z] abort #1 [2025-07-02T14:35:21.923Z] abort #2 [2025-07-02T14:35:21.923Z] request #0 [2025-07-02T14:35:21.923Z] request #1 [2025-07-02T14:35:21.923Z] request #2 [2025-07-02T14:35:21.923Z] Finished waiting [2025-07-02T14:35:21.923Z] send #0 [2025-07-02T14:35:21.923Z] send #1 [2025-07-02T14:35:21.923Z] send #2 [2025-07-02T14:35:21.923Z] test Abort.immediateAbort(): success [2025-07-02T14:35:21.923Z] onOpen(jdk.internal.net.http.websocket.WebSocketImpl@62b50b31[uri=ws://localhost:60313]) [2025-07-02T14:35:21.923Z] test Abort.onOpenThenAbort(): success [2025-07-02T14:35:21.923Z] onOpen(jdk.internal.net.http.websocket.WebSocketImpl@1bc7318a[uri=ws://localhost:60315]) [2025-07-02T14:35:21.923Z] onBinary(jdk.internal.net.http.websocket.WebSocketImpl@1bc7318a[uri=ws://localhost:60315], java.nio.HeapByteBufferR[pos=0 lim=0 cap=0], true) [2025-07-02T14:35:21.923Z] request(1) [2025-07-02T14:35:21.923Z] test Abort.onOpenThenOnBinaryThenAbort(): success [2025-07-02T14:35:21.923Z] onOpen(jdk.internal.net.http.websocket.WebSocketImpl@3baf20a4[uri=ws://localhost:60317]) [2025-07-02T14:35:21.923Z] onClose(jdk.internal.net.http.websocket.WebSocketImpl@3baf20a4[uri=ws://localhost:60317], 1005, ) [2025-07-02T14:35:21.923Z] test Abort.onOpenThenOnCloseThenAbort(): success [2025-07-02T14:35:21.923Z] onOpen(jdk.internal.net.http.websocket.WebSocketImpl@604a29cd[uri=ws://localhost:60319]) [2025-07-02T14:35:21.923Z] onError(jdk.internal.net.http.websocket.WebSocketImpl@604a29cd[uri=ws://localhost:60319], java.net.ProtocolException) [2025-07-02T14:35:21.923Z] java.net.ProtocolException [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processError(WebSocketImpl.java:495) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:462) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.923Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.923Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.923Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.923Z] Caused by: jdk.internal.net.http.websocket.FailWebSocketException: PING's payload length 128 [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.MessageDecoder.payloadLen(MessageDecoder.java:169) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.Frame$Reader.readFrame(Frame.java:423) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$ReceiveTask.run(TransportImpl.java:669) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$ReadEvent.handle(TransportImpl.java:766) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.checkEvents(RawChannelTube.java:174) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.onNext(RawChannelTube.java:204) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.onNext(RawChannelTube.java:157) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription.read(SocketTube.java:857) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube$SocketFlowTask.run(SocketTube.java:181) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription.signalReadable(SocketTube.java:774) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent.signalEvent(SocketTube.java:957) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube$SocketFlowEvent.handle(SocketTube.java:253) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:984) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.lambda$run$3(HttpClientImpl.java:939) [2025-07-02T14:35:21.923Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:939) [2025-07-02T14:35:21.923Z] actual invocations:[onOpen(jdk.internal.net.http.websocket.WebSocketImpl@604a29cd[uri=ws://localhost:60319]), onError(jdk.internal.net.http.websocket.WebSocketImpl@604a29cd[uri=ws://localhost:60319], class java.net.ProtocolException)] [2025-07-02T14:35:21.923Z] test Abort.onOpenThenOnErrorThenAbort(): success [2025-07-02T14:35:21.923Z] onOpen(jdk.internal.net.http.websocket.WebSocketImpl@18e19195[uri=ws://localhost:60321]) [2025-07-02T14:35:21.923Z] onPing(jdk.internal.net.http.websocket.WebSocketImpl@18e19195[uri=ws://localhost:60321], java.nio.HeapByteBuffer[pos=0 lim=0 cap=0]) [2025-07-02T14:35:21.923Z] request(1) [2025-07-02T14:35:21.923Z] STDERR: [2025-07-02T14:35:21.923Z] Starting [2025-07-02T14:35:21.923Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60311] [2025-07-02T14:35:21.923Z] Started at: ws://localhost:60311 [2025-07-02T14:35:21.923Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60311 remote=/127.0.0.1:60312] [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [364ms] [WebSocket] RawChannelTube(SocketTube(1)) connectFlows [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [364ms] [WebSocket] RawChannelTube(SocketTube(1)) WritePublisher::subscribe [2025-07-02T14:35:21.923Z] DEBUG: [HttpClient-1-SelectorManager] [364ms] [WebSocket] RawChannelTube(SocketTube(1)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.923Z] DEBUG: [HttpClient-1-Worker-0] [366ms] [WebSocket] RawChannelTube(SocketTube(1)) WriteSubscription::request 1 [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [369ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] signalOpen [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] enter receive task [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] exit receive task [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] enter receive task [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [372ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.923Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [373ms] [WebSocket] exit receive task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [WebSocket] abort [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [WebSocket] close [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [Transport] closeInput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [WebSocket] RawChannelTube(SocketTube(1)) shutdownInput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [Transport] closeOutput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [WebSocket] RawChannelTube(SocketTube(1)) shutdownOutput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [373ms] [WebSocket] RawChannelTube(SocketTube(1)) close [2025-07-02T14:35:21.923Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60311 remote=/127.0.0.1:60312] [2025-07-02T14:35:21.923Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60311] [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [375ms] [WebSocket] RawChannelTube(SocketTube(1)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.923Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.923Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.abort(WebSocketImpl.java:394) [2025-07-02T14:35:21.923Z] at Abort.immediateAbort(Abort.java:396) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2025-07-02T14:35:21.923Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2025-07-02T14:35:21.923Z] at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:132) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:599) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:174) [2025-07-02T14:35:21.923Z] at org.testng.internal.MethodRunner.runInSequence(MethodRunner.java:46) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestInvoker$MethodInvocationAgent.invoke(TestInvoker.java:822) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestInvoker.invokeTestMethods(TestInvoker.java:147) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:146) [2025-07-02T14:35:21.923Z] at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:128) [2025-07-02T14:35:21.923Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2025-07-02T14:35:21.923Z] at org.testng.TestRunner.privateRun(TestRunner.java:764) [2025-07-02T14:35:21.923Z] at org.testng.TestRunner.run(TestRunner.java:585) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunner.runTest(SuiteRunner.java:384) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:378) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunner.privateRun(SuiteRunner.java:337) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunner.run(SuiteRunner.java:286) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:53) [2025-07-02T14:35:21.923Z] at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:96) [2025-07-02T14:35:21.923Z] at org.testng.TestNG.runSuitesSequentially(TestNG.java:1218) [2025-07-02T14:35:21.923Z] at org.testng.TestNG.runSuitesLocally(TestNG.java:1140) [2025-07-02T14:35:21.923Z] at org.testng.TestNG.runSuites(TestNG.java:1069) [2025-07-02T14:35:21.923Z] at org.testng.TestNG.run(TestNG.java:1037) [2025-07-02T14:35:21.923Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:102) [2025-07-02T14:35:21.923Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:58) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2025-07-02T14:35:21.923Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2025-07-02T14:35:21.923Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2025-07-02T14:35:21.923Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2025-07-02T14:35:21.923Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [377ms] [Transport] enter send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [377ms] [Transport] exit send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [WebSocket] abort [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [WebSocket] close [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] closeInput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] closeOutput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] enter send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] exit send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [WebSocket] abort [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [WebSocket] close [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] closeInput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] closeOutput [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] enter send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [Transport] exit send task [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [380ms] [WebSocket] request 1 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [381ms] [WebSocket] request 2 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [381ms] [WebSocket] request 8 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [381ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [381ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [382ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.923Z] DEBUG: [MainThread] [382ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [383ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [384ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [385ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [386ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [387ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] abort [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [WebSocket] close [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeInput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] closeOutput [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [388ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [389ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 2 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 8 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 2147483647 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -2147483648 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -9223372036854775808 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request -1 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [390ms] [WebSocket] request 0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 391ms] [WebSocket] enter send text 1 payload length=5 last=false [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Transport] enter send text 1 message.length=5 last=false [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Transport] load message [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Output] put [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 392ms] [Output] mask [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] moreText [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] frame #0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] frame opcode=TEXT fin=false len=5 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] put [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] mask [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Output] moreText [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Transport] enter writing [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 393ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 394ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 394ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 394ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [Transport] exit send text 1 returned jdk.internal.net.http.common.MinimalFuture@332c1cd6[Completed exceptionally: java.io.IOException: Output closed] (id=54) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [WebSocket] exit send text 1 returned jdk.internal.net.http.common.MinimalFuture@332c1cd6[Completed exceptionally: java.io.IOException: Output closed] (id=54) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [WebSocket] enter send text 2 payload length=5 last=true [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [Transport] enter send text 2 message.length=5 last=true [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 409ms] [Transport] load message [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] put [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] mask [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] moreText [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] frame #0 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] frame opcode=CONTINUATION fin=true len=5 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] put [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] mask [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Output] moreText [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] enter writing [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [Transport] exit send text 2 returned jdk.internal.net.http.common.MinimalFuture@e5c5721[Completed exceptionally: java.io.IOException: Output closed] (id=55) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [WebSocket] exit send text 2 returned jdk.internal.net.http.common.MinimalFuture@e5c5721[Completed exceptionally: java.io.IOException: Output closed] (id=55) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 410ms] [WebSocket] enter send binary 3 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter send binary 3 message.remaining=16 last=false [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] load message [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Output] frame opcode=BINARY fin=false len=16 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter writing [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] exit send binary 3 returned jdk.internal.net.http.common.MinimalFuture@71ed069c[Completed exceptionally: java.io.IOException: Output closed] (id=56) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [WebSocket] exit send binary 3 returned jdk.internal.net.http.common.MinimalFuture@71ed069c[Completed exceptionally: java.io.IOException: Output closed] (id=56) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [WebSocket] enter send binary 4 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter send binary 4 message.remaining=16 last=true [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] load message [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Output] frame opcode=CONTINUATION fin=true len=16 [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 411ms] [Transport] enter writing [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [Transport] exit send task [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [Transport] exit send binary 4 returned jdk.internal.net.http.common.MinimalFuture@363bb92c[Completed exceptionally: java.io.IOException: Output closed] (id=57) [2025-07-02T14:35:21.924Z] DEBUG: [MainThread] [5s 412ms] [WebSocket] exit send binary 4 returned jdk.internal.net.http.common.MinimalFuture@363bb92c[Completed exceptionally: java.io.IOException: Output closed] (id=57) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [WebSocket] enter send ping 5 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] enter send ping 5 message.remaining=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Output] encode ping src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Output] frame opcode=PING fin=true len=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 412ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] exit send ping 5 returned jdk.internal.net.http.common.MinimalFuture@495ab9a5[Completed exceptionally: java.io.IOException: Output closed] (id=58) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [WebSocket] exit send ping 5 returned jdk.internal.net.http.common.MinimalFuture@495ab9a5[Completed exceptionally: java.io.IOException: Output closed] (id=58) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [WebSocket] enter send pong 6 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] enter send pong 6 message.remaining=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Output] encode pong src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Output] frame opcode=PONG fin=true len=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 413ms] [Transport] exit send pong 6 returned jdk.internal.net.http.common.MinimalFuture@3b03be02[Completed exceptionally: java.io.IOException: Output closed] (id=59) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 414ms] [WebSocket] exit send pong 6 returned jdk.internal.net.http.common.MinimalFuture@3b03be02[Completed exceptionally: java.io.IOException: Output closed] (id=59) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 414ms] [WebSocket] enter send close 7 statusCode=1000 reason.length=8 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 414ms] [WebSocket] exit send close 7 returned jdk.internal.net.http.common.MinimalFuture@6d5c0244[Completed exceptionally: java.io.IOException: Output closed] (id=60) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [WebSocket] enter send text 8 payload length=5 last=false [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] enter send text 7 message.length=5 last=false [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] put [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] mask [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] moreText [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] frame #0 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] frame opcode=TEXT fin=false len=5 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] put [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] mask [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Output] moreText [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 426ms] [Transport] exit send text 7 returned jdk.internal.net.http.common.MinimalFuture@110a284c[Completed exceptionally: java.io.IOException: Output closed] (id=61) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [WebSocket] exit send text 8 returned jdk.internal.net.http.common.MinimalFuture@110a284c[Completed exceptionally: java.io.IOException: Output closed] (id=61) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [WebSocket] enter send text 9 payload length=5 last=true [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] enter send text 8 message.length=5 last=true [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] put [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] mask [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] moreText [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] frame #0 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] frame opcode=CONTINUATION fin=true len=5 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] put [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] mask [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] moreText [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] exit send text 8 returned jdk.internal.net.http.common.MinimalFuture@1f8a8317[Completed exceptionally: java.io.IOException: Output closed] (id=62) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [WebSocket] exit send text 9 returned jdk.internal.net.http.common.MinimalFuture@1f8a8317[Completed exceptionally: java.io.IOException: Output closed] (id=62) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [WebSocket] enter send binary 10 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] enter send binary 9 message.remaining=16 last=false [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 427ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Output] frame opcode=BINARY fin=false len=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] exit send binary 9 returned jdk.internal.net.http.common.MinimalFuture@4b507019[Completed exceptionally: java.io.IOException: Output closed] (id=63) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [WebSocket] exit send binary 10 returned jdk.internal.net.http.common.MinimalFuture@4b507019[Completed exceptionally: java.io.IOException: Output closed] (id=63) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [WebSocket] enter send binary 11 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter send binary 10 message.remaining=16 last=true [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Output] frame opcode=CONTINUATION fin=true len=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] exit send binary 10 returned jdk.internal.net.http.common.MinimalFuture@6cac0533[Completed exceptionally: java.io.IOException: Output closed] (id=64) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [WebSocket] exit send binary 11 returned jdk.internal.net.http.common.MinimalFuture@6cac0533[Completed exceptionally: java.io.IOException: Output closed] (id=64) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [WebSocket] enter send ping 12 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter send ping 11 message.remaining=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 428ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] load message [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Output] encode ping src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Output] frame opcode=PING fin=true len=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] enter writing [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] exit send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] exit send ping 11 returned jdk.internal.net.http.common.MinimalFuture@2c2418a2[Completed exceptionally: java.io.IOException: Output closed] (id=65) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [WebSocket] exit send ping 12 returned jdk.internal.net.http.common.MinimalFuture@2c2418a2[Completed exceptionally: java.io.IOException: Output closed] (id=65) [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [WebSocket] enter send pong 13 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] enter send pong 12 message.remaining=16 [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] enter send task [2025-07-02T14:35:21.925Z] DEBUG: [MainThread] [5s 429ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Output] encode pong src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Output] frame opcode=PONG fin=true len=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 429ms] [Transport] exit send pong 12 returned jdk.internal.net.http.common.MinimalFuture@67fdb40a[Completed exceptionally: java.io.IOException: Output closed] (id=66) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [WebSocket] exit send pong 13 returned jdk.internal.net.http.common.MinimalFuture@67fdb40a[Completed exceptionally: java.io.IOException: Output closed] (id=66) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [WebSocket] enter send close 14 statusCode=1000 reason.length=8 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [WebSocket] exit send close 14 returned jdk.internal.net.http.common.MinimalFuture@2fe5d054[Completed exceptionally: java.io.IOException: Output closed] (id=67) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [WebSocket] enter send text 15 payload length=5 last=false [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [Transport] enter send text 13 message.length=5 last=false [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 435ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 436ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] put [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] mask [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] moreText [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] frame #0 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] frame opcode=TEXT fin=false len=5 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] put [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] mask [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Output] moreText [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [Transport] exit send text 13 returned jdk.internal.net.http.common.MinimalFuture@23ecdd06[Completed exceptionally: java.io.IOException: Output closed] (id=68) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 442ms] [WebSocket] exit send text 15 returned jdk.internal.net.http.common.MinimalFuture@23ecdd06[Completed exceptionally: java.io.IOException: Output closed] (id=68) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [WebSocket] enter send text 16 payload length=5 last=true [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter send text 14 message.length=5 last=true [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] encode text src=[pos=0 lim=5 cap=5] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] put [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] mask [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] moreText [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] frame #0 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] frame opcode=CONTINUATION fin=true len=5 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] put [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] mask [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] moreText [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] exit send text 14 returned jdk.internal.net.http.common.MinimalFuture@2f0130d1[Completed exceptionally: java.io.IOException: Output closed] (id=69) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [WebSocket] exit send text 16 returned jdk.internal.net.http.common.MinimalFuture@2f0130d1[Completed exceptionally: java.io.IOException: Output closed] (id=69) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [WebSocket] enter send binary 17 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter send binary 15 message.remaining=16 last=false [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=false dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Output] frame opcode=BINARY fin=false len=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 443ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] exit send binary 15 returned jdk.internal.net.http.common.MinimalFuture@25d68cdf[Completed exceptionally: java.io.IOException: Output closed] (id=70) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [WebSocket] exit send binary 17 returned jdk.internal.net.http.common.MinimalFuture@25d68cdf[Completed exceptionally: java.io.IOException: Output closed] (id=70) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [WebSocket] enter send binary 18 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] enter send binary 16 message.remaining=16 last=true [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Output] encode binary src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] last=true dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Output] frame opcode=CONTINUATION fin=true len=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] exit send binary 16 returned jdk.internal.net.http.common.MinimalFuture@24308f41[Completed exceptionally: java.io.IOException: Output closed] (id=71) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [WebSocket] exit send binary 18 returned jdk.internal.net.http.common.MinimalFuture@24308f41[Completed exceptionally: java.io.IOException: Output closed] (id=71) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [WebSocket] enter send ping 19 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] enter send ping 17 message.remaining=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Output] encode ping src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 444ms] [Output] frame opcode=PING fin=true len=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] exit send ping 17 returned jdk.internal.net.http.common.MinimalFuture@6171622c[Completed exceptionally: java.io.IOException: Output closed] (id=72) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [WebSocket] exit send ping 19 returned jdk.internal.net.http.common.MinimalFuture@6171622c[Completed exceptionally: java.io.IOException: Output closed] (id=72) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [WebSocket] enter send pong 20 payload=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] enter send pong 18 message.remaining=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] load message [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Output] encode pong src=java.nio.HeapByteBuffer[pos=0 lim=16 cap=16] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Output] frame opcode=PONG fin=true len=16 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] enter writing [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [Transport] exit send pong 18 returned jdk.internal.net.http.common.MinimalFuture@47d0516b[Completed exceptionally: java.io.IOException: Output closed] (id=73) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [WebSocket] exit send pong 20 returned jdk.internal.net.http.common.MinimalFuture@47d0516b[Completed exceptionally: java.io.IOException: Output closed] (id=73) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [WebSocket] enter send close 21 statusCode=1000 reason.length=8 [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 445ms] [WebSocket] exit send close 21 returned jdk.internal.net.http.common.MinimalFuture@56719d3a[Completed exceptionally: java.io.IOException: Output closed] (id=74) [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [WebSocket] abort [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [WebSocket] close [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [Transport] closeInput [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [Transport] closeOutput [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [Transport] enter send task [2025-07-02T14:35:21.926Z] DEBUG: [MainThread] [5s 476ms] [Transport] exit send task [2025-07-02T14:35:21.926Z] Stopping: ws://localhost:60311 [2025-07-02T14:35:21.926Z] Starting [2025-07-02T14:35:21.926Z] Started at: ws://localhost:60313 [2025-07-02T14:35:21.926Z] Stopped at: ws://localhost:60311 [2025-07-02T14:35:21.926Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60313] [2025-07-02T14:35:21.926Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60313 remote=/127.0.0.1:60314] [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] RawChannelTube(SocketTube(2)) connectFlows [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] RawChannelTube(SocketTube(2)) WritePublisher::subscribe [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] signalOpen [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] enter receive task [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 507ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.926Z] DEBUG: [HttpClient-2-SelectorManager] [5s 511ms] [WebSocket] RawChannelTube(SocketTube(2)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.926Z] DEBUG: [HttpClient-2-Worker-1] [5s 511ms] [WebSocket] RawChannelTube(SocketTube(2)) WriteSubscription::request 1 [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] abort [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] close [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [Transport] closeInput [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] RawChannelTube(SocketTube(2)) shutdownInput [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [Transport] closeOutput [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] RawChannelTube(SocketTube(2)) shutdownOutput [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] RawChannelTube(SocketTube(2)) close [2025-07-02T14:35:21.926Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 528ms] [WebSocket] RawChannelTube(SocketTube(2)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.926Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.abort(WebSocketImpl.java:394) [2025-07-02T14:35:21.926Z] at Abort$1.onOpen0(Abort.java:72) [2025-07-02T14:35:21.926Z] at MockListener.onOpen(MockListener.java:80) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processOpen(WebSocketImpl.java:650) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:439) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$DelegatingExecutor.execute(HttpClientImpl.java:158) [2025-07-02T14:35:21.926Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:305) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:274) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.signalOpen(WebSocketImpl.java:713) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.newInstance(WebSocketImpl.java:155) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.lambda$newInstanceAsync$0(WebSocketImpl.java:125) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:646) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.CompletableFuture.postFire(CompletableFuture.java:614) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:844) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.CompletableFuture$Completion.exec(CompletableFuture.java:483) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:373) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1182) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1655) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1622) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:165) [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 529ms] [Transport] enter send task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 529ms] [Transport] exit send task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 529ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 529ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [5s 529ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60313 remote=/127.0.0.1:60314] [2025-07-02T14:35:21.927Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60313] [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [WebSocket] abort [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [WebSocket] close [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [Transport] closeInput [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [Transport] closeOutput [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [Transport] enter send task [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [10s 533ms] [Transport] exit send task [2025-07-02T14:35:21.927Z] Stopping: ws://localhost:60313 [2025-07-02T14:35:21.927Z] Starting [2025-07-02T14:35:21.927Z] Started at: ws://localhost:60315 [2025-07-02T14:35:21.927Z] Stopped at: ws://localhost:60313 [2025-07-02T14:35:21.927Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60315] [2025-07-02T14:35:21.927Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60315 remote=/127.0.0.1:60316] [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] RawChannelTube(SocketTube(3)) connectFlows [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] RawChannelTube(SocketTube(3)) WritePublisher::subscribe [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] signalOpen [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] change state from IDLE to WAITING true [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [Transport] request 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [Transport] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [Transport] receive state: UNREGISTERED [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] RawChannelTube(SocketTube(3)) register read event [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [Transport] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] receive state: WAITING [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [10s 545ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 546ms] [WebSocket] RawChannelTube(SocketTube(3)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 546ms] [WebSocket] RawChannelTube(SocketTube(3)) readSubscription: requesting 1 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [WebSocket] RawChannelTube(SocketTube(3)) ReadSubscriber::onNext 4 bytes [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [WebSocket] RawChannelTube(SocketTube(3)) ReadSubscriber: handling event [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] read event [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] receive state: AVAILABLE [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [WebSocket] RawChannelTube(SocketTube(3)) read [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [WebSocket] RawChannelTube(SocketTube(3)) read: 4 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] remaining bytes received 4 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] fin true [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] rsv1 false [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] rsv2 false [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] rsv3 false [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] opcode BINARY [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] mask false [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] payloadLen 0 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] payload java.nio.HeapByteBufferR[pos=2 lim=2 cap=4] [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [WebSocket] change state from WAITING to BINARY true [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Input] end frame [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] remaining bytes received 2 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-SelectorManager] [10s 547ms] [Transport] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-1] [10s 556ms] [WebSocket] RawChannelTube(SocketTube(3)) WriteSubscription::request 1 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] receive state: BINARY [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] enter onBinary 2 payload=java.nio.HeapByteBufferR[pos=0 lim=0 cap=0] last=true [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] abort [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] close [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [Transport] closeInput [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] RawChannelTube(SocketTube(3)) shutdownInput [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [Transport] closeOutput [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] RawChannelTube(SocketTube(3)) shutdownOutput [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 556ms] [WebSocket] RawChannelTube(SocketTube(3)) close [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [WebSocket] RawChannelTube(SocketTube(3)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.927Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.abort(WebSocketImpl.java:394) [2025-07-02T14:35:21.927Z] at Abort$3.onBinary0(Abort.java:148) [2025-07-02T14:35:21.927Z] at MockListener.onBinary(MockListener.java:123) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processBinary(WebSocketImpl.java:618) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:447) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.927Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.927Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.927Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [Transport] enter send task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [Transport] exit send task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [WebSocket] request 1 [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [WebSocket] exit onBinary 2 returned null [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [WebSocket] change state from BINARY to IDLE true [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-3-Worker-0] [10s 557ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60315 remote=/127.0.0.1:60316] [2025-07-02T14:35:21.927Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60315] [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [WebSocket] abort [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [WebSocket] close [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [Transport] closeInput [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [Transport] closeOutput [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [Transport] enter send task [2025-07-02T14:35:21.927Z] DEBUG: [MainThread] [15s 556ms] [Transport] exit send task [2025-07-02T14:35:21.927Z] Stopping: ws://localhost:60315 [2025-07-02T14:35:21.927Z] Starting [2025-07-02T14:35:21.927Z] Started at: ws://localhost:60317 [2025-07-02T14:35:21.927Z] Stopped at: ws://localhost:60315 [2025-07-02T14:35:21.927Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60317] [2025-07-02T14:35:21.927Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60317 remote=/127.0.0.1:60318] [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] RawChannelTube(SocketTube(4)) connectFlows [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] RawChannelTube(SocketTube(4)) WritePublisher::subscribe [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] signalOpen [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] change state from IDLE to WAITING true [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [Transport] request 1 [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [Transport] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [Transport] receive state: UNREGISTERED [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] RawChannelTube(SocketTube(4)) register read event [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [Transport] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] enter receive task [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] receive state: WAITING [2025-07-02T14:35:21.927Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [15s 573ms] [WebSocket] exit receive task [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-4-SelectorManager] [15s 573ms] [WebSocket] RawChannelTube(SocketTube(4)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.927Z] DEBUG: [HttpClient-4-SelectorManager] [15s 573ms] [WebSocket] RawChannelTube(SocketTube(4)) readSubscription: requesting 1 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] RawChannelTube(SocketTube(4)) ReadSubscriber::onNext 4 bytes [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] RawChannelTube(SocketTube(4)) ReadSubscriber: handling event [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] read event [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] enter receive task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] receive state: AVAILABLE [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] RawChannelTube(SocketTube(4)) read [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] RawChannelTube(SocketTube(4)) read: 4 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] remaining bytes received 4 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] fin true [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] rsv1 false [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] rsv2 false [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] rsv3 false [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] opcode CLOSE [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] mask false [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] payloadLen 0 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] payload java.nio.HeapByteBufferR[pos=2 lim=2 cap=4] [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Input] end frame [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] set state CLOSE (previous WAITING) true [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] signalClose statusCode=1005 reason.length=0: true [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] closeInput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [WebSocket] RawChannelTube(SocketTube(4)) shutdownInput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-SelectorManager] [15s 574ms] [Transport] exit receive task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-1] [15s 575ms] [WebSocket] RawChannelTube(SocketTube(4)) WriteSubscription::request 1 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] enter receive task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] receive state: CLOSE [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] processClose [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [Transport] closeInput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] enter onClose 2 statusCode=1005 reason.length=0 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] abort [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] close [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [Transport] closeInput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [Transport] closeOutput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] RawChannelTube(SocketTube(4)) shutdownOutput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] RawChannelTube(SocketTube(4)) close [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] RawChannelTube(SocketTube(4)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.928Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.abort(WebSocketImpl.java:394) [2025-07-02T14:35:21.928Z] at Abort$6.onClose0(Abort.java:266) [2025-07-02T14:35:21.928Z] at MockListener.onClose(MockListener.java:181) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processClose(WebSocketImpl.java:528) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:459) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.928Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [Transport] enter send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [Transport] exit send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 575ms] [WebSocket] exit onClose 2 returned null [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 582ms] [WebSocket] using statusCode 1005 instead of 1000 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [WebSocket] CompletionStage returned by onClose completed result=jdk.internal.net.http.websocket.WebSocketImpl@3baf20a4[uri=ws://localhost:60317] error=null [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] enter send close 1 statusCode=1000 reason.length=0 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] enter send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] load message [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] encode close statusCode=1000 reason=[pos=0 lim=0 cap=0] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] reason [pos=0 lim=0 cap=0] [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] flushing [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] encoding result: UNDERFLOW [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] frame opcode=CLOSE fin=true len=2 [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Output] intermediateBuffer=java.nio.HeapByteBuffer[pos=0 lim=2 cap=16384] [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] enter writing [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 583ms] [WebSocket] send close completed with error java.io.IOException: Output closed [2025-07-02T14:35:21.928Z] java.io.IOException: Output closed [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$SendTask.tryCompleteWrite(TransportImpl.java:613) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$SendTask.run(TransportImpl.java:551) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.sendClose(TransportImpl.java:271) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.sendClose0(WebSocketImpl.java:345) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.sendCloseSilently(WebSocketImpl.java:660) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.lambda$processClose$0(WebSocketImpl.java:549) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:863) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.CompletableFuture.uniWhenCompleteStage(CompletableFuture.java:887) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:2325) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.CompletableFuture.whenComplete(CompletableFuture.java:144) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processClose(WebSocketImpl.java:544) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:459) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.928Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.928Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.928Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [Transport] closeOutput [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [Transport] exit send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [Transport] enter send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [Transport] exit send task [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [Transport] exit send close 1 returned jdk.internal.net.http.common.MinimalFuture@393e6dfa[Completed exceptionally: java.io.IOException: Output closed] (id=235) [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [WebSocket] automatic closure completed with error [2025-07-02T14:35:21.928Z] DEBUG: [HttpClient-4-Worker-0] [15s 584ms] [WebSocket] exit receive task [2025-07-02T14:35:21.928Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60317 remote=/127.0.0.1:60318] [2025-07-02T14:35:21.928Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60317] [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [WebSocket] abort [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [WebSocket] close [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [Transport] closeInput [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [Transport] closeOutput [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [Transport] enter send task [2025-07-02T14:35:21.928Z] DEBUG: [MainThread] [20s 575ms] [Transport] exit send task [2025-07-02T14:35:21.928Z] Stopping: ws://localhost:60317 [2025-07-02T14:35:21.928Z] Starting [2025-07-02T14:35:21.928Z] Started at: ws://localhost:60319 [2025-07-02T14:35:21.928Z] Stopped at: ws://localhost:60317 [2025-07-02T14:35:21.928Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60319] [2025-07-02T14:35:21.928Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60319 remote=/127.0.0.1:60320] [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] RawChannelTube(SocketTube(5)) connectFlows [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] RawChannelTube(SocketTube(5)) WritePublisher::subscribe [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] signalOpen [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] enter receive task [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] change state from IDLE to WAITING true [2025-07-02T14:35:21.928Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [Transport] request 1 [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [Transport] enter receive task [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [Transport] receive state: UNREGISTERED [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] RawChannelTube(SocketTube(5)) register read event [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [Transport] exit receive task [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] exit receive task [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] enter receive task [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] receive state: WAITING [2025-07-02T14:35:21.929Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [20s 590ms] [WebSocket] exit receive task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 590ms] [WebSocket] RawChannelTube(SocketTube(5)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 590ms] [WebSocket] RawChannelTube(SocketTube(5)) readSubscription: requesting 1 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) ReadSubscriber::onNext 134 bytes [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) ReadSubscriber: handling event [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] read event [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] enter receive task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] receive state: AVAILABLE [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) read [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) read: 134 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] remaining bytes received 134 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] fin true [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] rsv1 false [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] rsv2 false [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] rsv3 false [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] opcode PING [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] mask false [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Input] payloadLen 128 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] signalError jdk.internal.net.http.websocket.FailWebSocketException: PING's payload length 128 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] set state ERROR (previous WAITING) true [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] close [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] closeInput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) shutdownInput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] closeOutput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) shutdownOutput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) close [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] enter send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] exit send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [Transport] exit receive task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-SelectorManager] [20s 591ms] [WebSocket] RawChannelTube(SocketTube(5)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.929Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.signalError(WebSocketImpl.java:728) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$SignallingMessageConsumer.onError(WebSocketImpl.java:833) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$ReceiveTask.run(TransportImpl.java:675) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$ReadEvent.handle(TransportImpl.java:766) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.checkEvents(RawChannelTube.java:174) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.onNext(RawChannelTube.java:204) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.RawChannelTube$ReadSubscriber.onNext(RawChannelTube.java:157) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription.read(SocketTube.java:857) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube$SocketFlowTask.run(SocketTube.java:181) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$InternalReadSubscription.signalReadable(SocketTube.java:774) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent.signalEvent(SocketTube.java:957) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.SocketTube$SocketFlowEvent.handle(SocketTube.java:253) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.handleEvent(HttpClientImpl.java:984) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.lambda$run$3(HttpClientImpl.java:939) [2025-07-02T14:35:21.929Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$SelectorManager.run(HttpClientImpl.java:939) [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-1] [20s 596ms] [WebSocket] RawChannelTube(SocketTube(5)) WriteSubscription::request 1 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] enter receive task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] receive state: ERROR [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] processError [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] closeInput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] failing jdk.internal.net.http.websocket.WebSocketImpl@604a29cd[uri=ws://localhost:60319] with error=java.net.ProtocolException statusCode=1002 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] enter send close 1 statusCode=1002 reason.length=0 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] enter send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] load message [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] encode close statusCode=1002 reason=[pos=0 lim=0 cap=0] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] reason [pos=0 lim=0 cap=0] [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] flushing [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] encoding result: UNDERFLOW [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] frame opcode=CLOSE fin=true len=2 [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Output] intermediateBuffer=java.nio.HeapByteBuffer[pos=0 lim=2 cap=16384] [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] enter writing [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] write state: CLOSED [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] send task exception java.io.IOException: Output closed [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] removeAndComplete error=java.io.IOException: Output closed [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] send close completed with error java.io.IOException: Output closed [2025-07-02T14:35:21.929Z] java.io.IOException: Output closed [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$SendTask.tryCompleteWrite(TransportImpl.java:613) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl$SendTask.run(TransportImpl.java:551) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.sendClose(TransportImpl.java:271) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.sendClose0(WebSocketImpl.java:345) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.sendCloseSilently(WebSocketImpl.java:660) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processError(WebSocketImpl.java:500) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:462) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.929Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.929Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.929Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.929Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] closeOutput [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] exit send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] enter send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] exit send task [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [Transport] exit send close 1 returned jdk.internal.net.http.common.MinimalFuture@38975215[Completed exceptionally: java.io.IOException: Output closed] (id=290) [2025-07-02T14:35:21.929Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] automatic closure completed with error [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 596ms] [WebSocket] enter onError 2 error=java.net.ProtocolException [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [WebSocket] abort [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [WebSocket] close [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [Transport] closeInput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [Transport] closeOutput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [Transport] enter send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [Transport] exit send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [WebSocket] exit onError 2 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-5-Worker-0] [20s 597ms] [WebSocket] exit receive task [2025-07-02T14:35:21.930Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60319 remote=/127.0.0.1:60320] [2025-07-02T14:35:21.930Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60319] [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [WebSocket] abort [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [WebSocket] close [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [Transport] closeInput [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [Transport] closeOutput [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [Transport] enter send task [2025-07-02T14:35:21.930Z] DEBUG: [MainThread] [25s 593ms] [Transport] exit send task [2025-07-02T14:35:21.930Z] Stopping: ws://localhost:60319 [2025-07-02T14:35:21.930Z] Starting [2025-07-02T14:35:21.930Z] Started at: ws://localhost:60321 [2025-07-02T14:35:21.930Z] Stopped at: ws://localhost:60319 [2025-07-02T14:35:21.930Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60321] [2025-07-02T14:35:21.930Z] Accepted: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60321 remote=/127.0.0.1:60322] [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 603ms] [WebSocket] RawChannelTube(SocketTube(6)) connectFlows [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 603ms] [WebSocket] RawChannelTube(SocketTube(6)) WritePublisher::subscribe [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [Transport] write buffer capacity 16384 [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] signalOpen [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] enter receive task [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] receive state: OPEN [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] enter onOpen 1 [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] request 9223372036854775807 [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] exit onOpen 1 [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] change state from OPEN to IDLE true [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] receive state: IDLE [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] change state from IDLE to WAITING true [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [Transport] request 1 [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [Transport] enter receive task [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [Transport] receive state: UNREGISTERED [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] RawChannelTube(SocketTube(6)) register read event [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [Transport] exit receive task [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] exit receive task [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] enter receive task [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] receive state: WAITING [2025-07-02T14:35:21.930Z] DEBUG: [ForkJoinPool.commonPool-worker-1] [25s 604ms] [WebSocket] exit receive task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) ReadSubscriber::onSubscribe [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) readSubscription: requesting 1 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) ReadSubscriber::onNext 4 bytes [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) ReadSubscriber: handling event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] read event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] enter receive task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] receive state: AVAILABLE [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) read [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) read: 4 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] remaining bytes received 4 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] fin true [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] rsv1 false [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] rsv2 false [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] rsv3 false [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] opcode PING [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] mask false [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] payloadLen 0 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] payload java.nio.HeapByteBufferR[pos=2 lim=2 cap=4] [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Input] end frame [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [WebSocket] change state from WAITING to PING true [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] remaining bytes received 2 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-SelectorManager] [25s 605ms] [Transport] exit receive task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-1] [25s 605ms] [WebSocket] RawChannelTube(SocketTube(6)) WriteSubscription::request 1 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 605ms] [WebSocket] enter receive task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 605ms] [WebSocket] receive state: PING [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 605ms] [WebSocket] processPing [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 605ms] [WebSocket] swapped automatic pong from null to java.nio.HeapByteBuffer[pos=0 lim=0 cap=0] [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] enter send pong 1 supplier=jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask$$Lambda$277/0x00000010001fb2f0@73ea6cdc [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] enter send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] load message [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Output] encode pong src=java.nio.HeapByteBuffer[pos=0 lim=0 cap=0] dst=java.nio.HeapByteBuffer[pos=0 lim=16384 cap=16384] [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Output] frame opcode=PONG fin=true len=0 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] enter writing [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] write state: UNREGISTERED [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] registering write event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [WebSocket] RawChannelTube(SocketTube(6)) register write event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] write event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] registered write event [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] exit writing [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] exit send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] enter send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] 6 bytes remaining in buffer java.nio.HeapByteBuffer[pos=0 lim=6 cap=16384] [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] enter writing [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] write state: AVAILABLE [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] writing to the channel [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [WebSocket] RawChannelTube(SocketTube(6)) write: writing 6 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [WebSocket] RawChannelTube(SocketTube(6)) WriteSubscription::request 1 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] 6 bytes written [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] finished writing to the channel [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] exit writing [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] removeAndComplete error=null [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] exit send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [Transport] exit send pong 1 returned jdk.internal.net.http.common.MinimalFuture@4bbaad83[Completed normally] (id=345) [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 606ms] [WebSocket] enter onPing 2 payload=java.nio.HeapByteBuffer[pos=0 lim=0 cap=0] [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] abort [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] close [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [Transport] closeInput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] RawChannelTube(SocketTube(6)) shutdownInput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [Transport] closeOutput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] RawChannelTube(SocketTube(6)) shutdownOutput [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] RawChannelTube(SocketTube(6)) close [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] RawChannelTube(SocketTube(6)) ReadSubscriber::onError java.io.IOException: connection closed locally [2025-07-02T14:35:21.930Z] java.io.IOException: connection closed locally [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.SocketTube.signalClosed(SocketTube.java:159) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.PlainHttpConnection.close(PlainHttpConnection.java:372) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.RawChannelTube.close(RawChannelTube.java:421) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.websocket.TransportImpl.closeOutput(TransportImpl.java:313) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.close(WebSocketImpl.java:744) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl.abort(WebSocketImpl.java:394) [2025-07-02T14:35:21.930Z] at Abort$4.onPing0(Abort.java:187) [2025-07-02T14:35:21.930Z] at MockListener.onPing(MockListener.java:143) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.processPing(WebSocketImpl.java:601) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.websocket.WebSocketImpl$ReceiveTask.run(WebSocketImpl.java:451) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2025-07-02T14:35:21.930Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2025-07-02T14:35:21.930Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2025-07-02T14:35:21.930Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2025-07-02T14:35:21.930Z] at java.base/java.lang.Thread.run(Thread.java:840) [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [Transport] enter send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [Transport] exit send task [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 610ms] [WebSocket] request 1 [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 611ms] [WebSocket] exit onPing 2 returned null [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 611ms] [WebSocket] change state from PING to IDLE true [2025-07-02T14:35:21.930Z] DEBUG: [HttpClient-6-Worker-0] [25s 611ms] [WebSocket] exit receive task [2025-07-02T14:35:21.930Z] Closed: java.nio.channels.SocketChannel[connected local=/127.0.0.1:60321 remote=/127.0.0.1:60322] [2025-07-02T14:35:21.930Z] Accepting next connection at: sun.nio.ch.ServerSocketChannelImpl[/127.0.0.1:60321] [2025-07-02T14:35:21.930Z] rerun: [2025-07-02T14:35:21.930Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/scratch && \ [2025-07-02T14:35:21.930Z] DISPLAY=unix:0 \ [2025-07-02T14:35:21.930Z] HOME=/home/jenkins \ [2025-07-02T14:35:21.930Z] LANG=en_US \ [2025-07-02T14:35:21.930Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:21.930Z] TZ=Etc/UTC \ [2025-07-02T14:35:21.931Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image/bin/java \ [2025-07-02T14:35:21.931Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ [2025-07-02T14:35:21.931Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ [2025-07-02T14:35:21.931Z] -Dtest.compiler.opts= \ [2025-07-02T14:35:21.931Z] -Dtest.java.opts= \ [2025-07-02T14:35:21.931Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.931Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.931Z] -Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:21.931Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-07-02T14:35:21.931Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-07-02T14:35:21.931Z] -Dtest.name=java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.931Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/Abort.java \ [2025-07-02T14:35:21.931Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.931Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d \ [2025-07-02T14:35:21.931Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/Abort.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-07-02T14:35:21.931Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] -ea \ [2025-07-02T14:35:21.931Z] -esa \ [2025-07-02T14:35:21.931Z] -Xmx512m \ [2025-07-02T14:35:21.931Z] -XX:+UseCompressedOops \ [2025-07-02T14:35:21.931Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-07-02T14:35:21.931Z] -Djdk.internal.httpclient.websocket.debug=true \ [2025-07-02T14:35:21.931Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/java/net/httpclient/websocket/Abort.d/testng.0.jta java/net/httpclient/websocket/Abort.java false Abort [2025-07-02T14:35:21.931Z] [2025-07-02T14:35:21.931Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 143] [2025-07-02T14:35:21.931Z] -------------------------------------------------- [2025-07-02T14:35:21.931Z] TEST: java/net/httpclient/websocket/AutomaticPong.java [2025-07-02T14:35:21.931Z] TEST JDK: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image [2025-07-02T14:35:21.931Z] [2025-07-02T14:35:21.931Z] ACTION: build -- Error. Agent communication error: java.net.SocketException: There is no process to read data written to a pipe.; check console log for any additional details [2025-07-02T14:35:21.931Z] REASON: User specified action: run build DummyWebSocketServer [2025-07-02T14:35:21.931Z] TIME: 0.003 seconds [2025-07-02T14:35:21.931Z] messages: [2025-07-02T14:35:21.931Z] command: build DummyWebSocketServer [2025-07-02T14:35:21.931Z] reason: User specified action: run build DummyWebSocketServer [2025-07-02T14:35:21.931Z] started: Wed Jul 02 14:35:10 UTC 2025 [2025-07-02T14:35:21.931Z] Test directory: [2025-07-02T14:35:21.931Z] compile: DummyWebSocketServer [2025-07-02T14:35:21.931Z] finished: Wed Jul 02 14:35:10 UTC 2025 [2025-07-02T14:35:21.931Z] elapsed time (seconds): 0.003 [2025-07-02T14:35:21.931Z] [2025-07-02T14:35:21.931Z] ACTION: compile -- Error. Agent communication error: java.net.SocketException: There is no process to read data written to a pipe.; check console log for any additional details [2025-07-02T14:35:21.931Z] REASON: .class file out of date or does not exist [2025-07-02T14:35:21.931Z] TIME: 0.003 seconds [2025-07-02T14:35:21.931Z] messages: [2025-07-02T14:35:21.931Z] command: compile /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/DummyWebSocketServer.java [2025-07-02T14:35:21.931Z] reason: .class file out of date or does not exist [2025-07-02T14:35:21.931Z] started: Wed Jul 02 14:35:10 UTC 2025 [2025-07-02T14:35:21.931Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-07-02T14:35:21.931Z] Mode: agentvm [2025-07-02T14:35:21.931Z] Agent id: 1 [2025-07-02T14:35:21.931Z] finished: Wed Jul 02 14:35:10 UTC 2025 [2025-07-02T14:35:21.931Z] elapsed time (seconds): 0.003 [2025-07-02T14:35:21.931Z] configuration: [2025-07-02T14:35:21.931Z] Boot Layer (javac runtime environment) [2025-07-02T14:35:21.931Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-07-02T14:35:21.931Z] patch: java.base /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/patches/java.base [2025-07-02T14:35:21.931Z] [2025-07-02T14:35:21.931Z] javac compilation environment [2025-07-02T14:35:21.931Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-07-02T14:35:21.931Z] add exports: java.base/sun.net ALL-UNNAMED [2025-07-02T14:35:21.931Z] java.base/sun.net.www ALL-UNNAMED [2025-07-02T14:35:21.931Z] java.base/sun.net.www.http ALL-UNNAMED [2025-07-02T14:35:21.931Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-07-02T14:35:21.931Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-07-02T14:35:21.931Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-07-02T14:35:21.931Z] source path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.931Z] class path: /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar [2025-07-02T14:35:21.931Z] [2025-07-02T14:35:21.931Z] rerun: [2025-07-02T14:35:21.931Z] cd /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/scratch && \ [2025-07-02T14:35:21.931Z] DISPLAY=unix:0 \ [2025-07-02T14:35:21.931Z] HOME=/home/jenkins \ [2025-07-02T14:35:21.931Z] LANG=en_US \ [2025-07-02T14:35:21.931Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-07-02T14:35:21.931Z] TZ=Etc/UTC \ [2025-07-02T14:35:21.931Z] /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image/bin/javac \ [2025-07-02T14:35:21.931Z] -J-ea \ [2025-07-02T14:35:21.931Z] -J-esa \ [2025-07-02T14:35:21.931Z] -J-Xmx512m \ [2025-07-02T14:35:21.931Z] -J-XX:+UseCompressedOops \ [2025-07-02T14:35:21.931Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops' \ [2025-07-02T14:35:21.931Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops' \ [2025-07-02T14:35:21.931Z] -J-Dtest.compiler.opts= \ [2025-07-02T14:35:21.931Z] -J-Dtest.java.opts= \ [2025-07-02T14:35:21.931Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.931Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/j2sdk-image \ [2025-07-02T14:35:21.931Z] -J-Dtest.timeout.factor=8.0 \ [2025-07-02T14:35:21.931Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-07-02T14:35:21.931Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-07-02T14:35:21.931Z] -J-Dtest.name=java/net/httpclient/websocket/AutomaticPong.java \ [2025-07-02T14:35:21.931Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/AutomaticPong.java \ [2025-07-02T14:35:21.931Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d \ [2025-07-02T14:35:21.931Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d \ [2025-07-02T14:35:21.931Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.931Z] -J-Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-07-02T14:35:21.931Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-07-02T14:35:21.931Z] -d /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d \ [2025-07-02T14:35:21.931Z] -sourcepath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket \ [2025-07-02T14:35:21.932Z] -classpath /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/TKG/output_17514651618206/jdk_net_0/work/classes/java/net/httpclient/websocket/AutomaticPong.d:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/testng-7.3.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/guice-5.1.0.jar:/home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/jvmtest/openjdk/jtreg/lib/jcommander-1.82.jar /home/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_ppc64_aix_testList_1/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/websocket/DummyWebSocketServer.java [2025-07-02T14:35:21.932Z] [2025-07-02T14:35:21.932Z] TEST RESULT: Error. Agent communication error: java.net.SocketException: There is no process to read data written to a pipe.; check console log for any additional details [2025-07-02T14:35:21.932Z] -------------------------------------------------- [2025-07-02T14:35:21.932Z] ----------------------------------- [2025-07-02T14:35:21.932Z] jdk_net_0_FAILED [2025-07-02T14:35:21.932Z] ----------------------------------- [2025-07-02T14:35:21.932Z] [2025-07-02T14:35:21.932Z] TEST TEARDOWN: [2025-07-02T14:35:21.932Z] Nothing to be done for teardown. [2025-07-02T14:35:21.932Z] jdk_net_0 Finish Time: Wed Jul 2 14:35:10 2025 Epoch Time (ms): 1751466910812