[2026-02-05T10:12:54.486Z] Running test jdk_net_1 ... [2026-02-05T10:12:54.486Z] =============================================== [2026-02-05T10:12:54.486Z] jdk_net_1 Start Time: Thu Feb 5 10:12:54 2026 Epoch Time (ms): 1770286374446 [2026-02-05T10:12:54.810Z] variation: Mode650 [2026-02-05T10:12:54.810Z] JVM_OPTIONS: -XX:-UseCompressedOops [2026-02-05T10:12:54.810Z] { \ [2026-02-05T10:12:54.810Z] echo ""; echo "TEST SETUP:"; \ [2026-02-05T10:12:54.810Z] echo "Nothing to be done for setup."; \ [2026-02-05T10:12:54.810Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17702584678675\\jdk_net_1"; \ [2026-02-05T10:12:54.810Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17702584678675\\jdk_net_1"; \ [2026-02-05T10:12:54.810Z] echo ""; echo "TESTING:"; \ [2026-02-05T10:12:54.810Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/jdkbinary/j2sdk-image\\bin\\java" -Xmx512m -jar "C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests///..//jvmtest\\openjdk\\jtreg\\lib\\jtreg.jar" \ [2026-02-05T10:12:54.810Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:8 -xml:verify -concurrency:2 -nativepath:"c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/jdkbinary/openjdk-test-image\\jdk\\jtreg\\native" -vmoptions:"-Xmx512m -XX:-UseCompressedOops " \ [2026-02-05T10:12:54.810Z] -w ""C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17702584678675\\jdk_net_1"\\work" \ [2026-02-05T10:12:54.810Z] -r ""C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17702584678675\\jdk_net_1"\\report" \ [2026-02-05T10:12:54.810Z] -jdk:"c:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/jdkbinary/j2sdk-image" \ [2026-02-05T10:12:54.810Z] -exclude:"C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\openjdk\\openjdk-jdk\\test\\jdk\\ProblemList.txt" \ [2026-02-05T10:12:54.810Z] -exclude:"C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests///..//jvmtest\\openjdk\\excludes/ProblemList_openjdk17.txt" \ [2026-02-05T10:12:54.810Z] \ [2026-02-05T10:12:54.810Z] -exclude:"C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\openjdk\\excludes\\vendors\\eclipse\\ProblemList_openjdk17.txt" \ [2026-02-05T10:12:54.810Z] "C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\openjdk\\openjdk-jdk\\test\\jdk:jdk_net"; \ [2026-02-05T10:12:54.810Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "jdk_net_1""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/; else echo "-----------------------------------"; echo "jdk_net_1""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-05T10:12:54.810Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-05T10:12:54.810Z] echo "Nothing to be done for teardown."; \ [2026-02-05T10:12:54.810Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17702584678675\\TestTargetResult"; [2026-02-05T10:12:54.810Z] [2026-02-05T10:12:54.810Z] TEST SETUP: [2026-02-05T10:12:54.810Z] Nothing to be done for setup. [2026-02-05T10:12:54.810Z] [2026-02-05T10:12:54.810Z] TESTING: [2026-02-05T10:12:55.494Z] Directory "C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work" not found: creating [2026-02-05T10:12:55.494Z] Directory "C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\report" not found: creating [2026-02-05T10:13:06.137Z] XML output with verification to C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work [2026-02-05T10:18:14.978Z] -------------------------------------------------- [2026-02-05T10:18:14.978Z] TEST: java/net/httpclient/http2/ConnectionFlowControlTest.java [2026-02-05T10:18:14.978Z] TEST JDK: c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image [2026-02-05T10:18:14.978Z] [2026-02-05T10:18:14.978Z] ACTION: build -- Passed. All files up to date [2026-02-05T10:18:14.978Z] REASON: User specified action: run build jdk.httpclient.test.lib.http2.Http2TestServer jdk.test.lib.net.SimpleSSLContext [2026-02-05T10:18:14.978Z] TIME: 0.002 seconds [2026-02-05T10:18:14.978Z] messages: [2026-02-05T10:18:14.978Z] command: build jdk.httpclient.test.lib.http2.Http2TestServer jdk.test.lib.net.SimpleSSLContext [2026-02-05T10:18:14.978Z] reason: User specified action: run build jdk.httpclient.test.lib.http2.Http2TestServer jdk.test.lib.net.SimpleSSLContext [2026-02-05T10:18:14.978Z] started: Thu Feb 05 10:17:52 UTC 2026 [2026-02-05T10:18:14.978Z] finished: Thu Feb 05 10:17:52 UTC 2026 [2026-02-05T10:18:14.978Z] elapsed time (seconds): 0.002 [2026-02-05T10:18:14.978Z] [2026-02-05T10:18:14.978Z] ACTION: build -- Passed. Build successful [2026-02-05T10:18:14.978Z] REASON: Named class compiled on demand [2026-02-05T10:18:14.978Z] TIME: 0.627 seconds [2026-02-05T10:18:14.978Z] messages: [2026-02-05T10:18:14.978Z] command: build ConnectionFlowControlTest [2026-02-05T10:18:14.978Z] reason: Named class compiled on demand [2026-02-05T10:18:14.978Z] started: Thu Feb 05 10:17:52 UTC 2026 [2026-02-05T10:18:14.978Z] Test directory: [2026-02-05T10:18:14.978Z] compile: ConnectionFlowControlTest [2026-02-05T10:18:14.978Z] finished: Thu Feb 05 10:17:53 UTC 2026 [2026-02-05T10:18:14.978Z] elapsed time (seconds): 0.627 [2026-02-05T10:18:14.978Z] [2026-02-05T10:18:14.978Z] ACTION: compile -- Passed. Compilation successful [2026-02-05T10:18:14.978Z] REASON: .class file out of date or does not exist [2026-02-05T10:18:14.978Z] TIME: 0.626 seconds [2026-02-05T10:18:14.978Z] messages: [2026-02-05T10:18:14.978Z] command: compile C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2\ConnectionFlowControlTest.java [2026-02-05T10:18:14.978Z] reason: .class file out of date or does not exist [2026-02-05T10:18:14.978Z] started: Thu Feb 05 10:17:52 UTC 2026 [2026-02-05T10:18:14.978Z] 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 [2026-02-05T10:18:14.978Z] Mode: agentvm [2026-02-05T10:18:14.978Z] Agent id: 1 [2026-02-05T10:18:14.978Z] finished: Thu Feb 05 10:17:53 UTC 2026 [2026-02-05T10:18:14.978Z] elapsed time (seconds): 0.626 [2026-02-05T10:18:14.978Z] configuration: [2026-02-05T10:18:14.978Z] Boot Layer (javac runtime environment) [2026-02-05T10:18:14.978Z] class path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\javatest.jar [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jtreg.jar [2026-02-05T10:18:14.978Z] patch: java.base C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\patches\java.base [2026-02-05T10:18:14.978Z] [2026-02-05T10:18:14.978Z] javac compilation environment [2026-02-05T10:18:14.978Z] add modules: java.base java.net.http java.logging jdk.httpserver [2026-02-05T10:18:14.978Z] add exports: java.base/sun.net ALL-UNNAMED [2026-02-05T10:18:14.978Z] java.base/sun.net.www ALL-UNNAMED [2026-02-05T10:18:14.978Z] java.base/sun.net.www.http ALL-UNNAMED [2026-02-05T10:18:14.978Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2026-02-05T10:18:14.978Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2026-02-05T10:18:14.978Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2026-02-05T10:18:14.978Z] source path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2 [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\lib [2026-02-05T10:18:14.978Z] class path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2 [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\testng-7.3.0.jar [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\guice-5.1.0.jar [2026-02-05T10:18:14.978Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jcommander-1.82.jar [2026-02-05T10:18:14.978Z] [2026-02-05T10:18:14.978Z] rerun: [2026-02-05T10:18:14.978Z] cd 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\scratch\0' && \ [2026-02-05T10:18:14.978Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\Program Files (x86)\Incredibuild;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Program Files\dotnet;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2026-02-05T10:18:14.978Z] SystemDrive=C: \ [2026-02-05T10:18:14.978Z] SystemRoot='C:\Windows' \ [2026-02-05T10:18:14.978Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:18:14.978Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:18:14.978Z] windir='C:\Windows' \ [2026-02-05T10:18:14.978Z] 'c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image\bin\javac' \ [2026-02-05T10:18:14.979Z] -J-ea \ [2026-02-05T10:18:14.979Z] -J-esa \ [2026-02-05T10:18:14.979Z] -J-Xmx512m \ [2026-02-05T10:18:14.979Z] -J-XX:-UseCompressedOops \ [2026-02-05T10:18:14.979Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2026-02-05T10:18:14.979Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2026-02-05T10:18:14.979Z] -J-Dtest.compiler.opts= \ [2026-02-05T10:18:14.979Z] -J-Dtest.java.opts= \ [2026-02-05T10:18:14.979Z] -J-Dtest.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:18:14.979Z] -J-Dcompile.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:18:14.979Z] -J-Dtest.timeout.factor=8.0 \ [2026-02-05T10:18:14.979Z] -J-Dtest.nativepath='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:18:14.979Z] -J-Dtest.root='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk' \ [2026-02-05T10:18:14.979Z] -J-Dtest.name=java/net/httpclient/http2/ConnectionFlowControlTest.java \ [2026-02-05T10:18:14.979Z] -J-Dtest.file='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2\ConnectionFlowControlTest.java' \ [2026-02-05T10:18:14.979Z] -J-Dtest.src='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2' \ [2026-02-05T10:18:14.979Z] -J-Dtest.src.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.979Z] -J-Dtest.classes='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d' \ [2026-02-05T10:18:14.979Z] -J-Dtest.class.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.979Z] -J-Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.979Z] -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' \ [2026-02-05T10:18:14.979Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2026-02-05T10:18:14.979Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2026-02-05T10:18:14.979Z] -d 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d' \ [2026-02-05T10:18:14.979Z] -sourcepath 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.979Z] -classpath 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\testng-7.3.0.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\guice-5.1.0.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jcommander-1.82.jar' 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2\ConnectionFlowControlTest.java' [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] ACTION: testng -- Failed. Execution failed: `main' threw exception: java.lang.Exception: failures: 1 [2026-02-05T10:18:14.979Z] REASON: User specified action: run testng/othervm -Djdk.internal.httpclient.debug=true -Djdk.httpclient.connectionWindowSize=65535 -Djdk.httpclient.windowsize=16384 ConnectionFlowControlTest [2026-02-05T10:18:14.979Z] TIME: 8.279 seconds [2026-02-05T10:18:14.979Z] messages: [2026-02-05T10:18:14.979Z] command: testng -Djdk.internal.httpclient.debug=true -Djdk.httpclient.connectionWindowSize=65535 -Djdk.httpclient.windowsize=16384 ConnectionFlowControlTest [2026-02-05T10:18:14.979Z] reason: User specified action: run testng/othervm -Djdk.internal.httpclient.debug=true -Djdk.httpclient.connectionWindowSize=65535 -Djdk.httpclient.windowsize=16384 ConnectionFlowControlTest [2026-02-05T10:18:14.979Z] started: Thu Feb 05 10:17:53 UTC 2026 [2026-02-05T10:18:14.979Z] Mode: othervm [/othervm specified] [2026-02-05T10:18:14.979Z] 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 [2026-02-05T10:18:14.979Z] finished: Thu Feb 05 10:18:01 UTC 2026 [2026-02-05T10:18:14.979Z] elapsed time (seconds): 8.279 [2026-02-05T10:18:14.979Z] configuration: [2026-02-05T10:18:14.979Z] Boot Layer [2026-02-05T10:18:14.979Z] add modules: java.base java.net.http java.logging jdk.httpserver [2026-02-05T10:18:14.979Z] add exports: java.base/sun.net ALL-UNNAMED [2026-02-05T10:18:14.979Z] java.base/sun.net.www ALL-UNNAMED [2026-02-05T10:18:14.979Z] java.base/sun.net.www.http ALL-UNNAMED [2026-02-05T10:18:14.979Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2026-02-05T10:18:14.979Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2026-02-05T10:18:14.979Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] STDOUT: [2026-02-05T10:18:14.979Z] Http2TestServerImpl: start [2026-02-05T10:18:14.979Z] Http2TestServerImpl: start [2026-02-05T10:18:14.979Z] config ConnectionFlowControlTest.setup(): success [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] testing http://127.0.0.1:54579/http2/x [2026-02-05T10:18:14.979Z] connection window: 65535, stream window: 16384, will make 5 requests [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending request:http://127.0.0.1:54579/http2/x?reqId=1 [2026-02-05T10:18:14.979Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.979Z] http://127.0.0.1:54579/http2/x?reqId=1: Hello there! [2026-02-05T10:18:14.979Z] Server: response sent for reqId=1 [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending request:http://127.0.0.1:54579/http2/x?reqId=2 [2026-02-05T10:18:14.979Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.979Z] http://127.0.0.1:54579/http2/x?reqId=2: Hello there! [2026-02-05T10:18:14.979Z] Server: response sent for reqId=2 [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending request:http://127.0.0.1:54579/http2/x?reqId=3 [2026-02-05T10:18:14.979Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.979Z] http://127.0.0.1:54579/http2/x?reqId=3: Hello there! [2026-02-05T10:18:14.979Z] Server: response sent for reqId=3 [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending request:http://127.0.0.1:54579/http2/x?reqId=4 [2026-02-05T10:18:14.979Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.979Z] http://127.0.0.1:54579/http2/x?reqId=4: Hello there! [2026-02-05T10:18:14.979Z] Server: response sent for reqId=4 [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending request:http://127.0.0.1:54579/http2/x?reqId=5 [2026-02-05T10:18:14.979Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.979Z] http://127.0.0.1:54579/http2/x?reqId=5: Hello there! [2026-02-05T10:18:14.979Z] Server: response sent for reqId=5 [2026-02-05T10:18:14.979Z] Got EXPECTED: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.979Z] Found expected exception: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.979Z] [2026-02-05T10:18:14.979Z] Sending last request:http://127.0.0.1:54579/http2/x?reqId=6 [2026-02-05T10:18:14.980Z] Server localhost/127.0.0.1:54579 received: [2026-02-05T10:18:14.980Z] http://127.0.0.1:54579/http2/x?reqId=6: Hello there! [2026-02-05T10:18:14.980Z] Server: response sent for reqId=6 [2026-02-05T10:18:14.980Z] last request reqId=6 sent on different connection as expected: [2026-02-05T10:18:14.980Z] last: localhost/127.0.0.1:54579->/127.0.0.1:54582 [2026-02-05T10:18:14.980Z] previous: localhost/127.0.0.1:54579->/127.0.0.1:54581 [2026-02-05T10:18:14.980Z] test ConnectionFlowControlTest.test("http://127.0.0.1:54579/http2/x"): success [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] testing https://127.0.0.1:54580/https2/x [2026-02-05T10:18:14.980Z] connection window: 65535, stream window: 16384, will make 5 requests [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] Sending request:https://127.0.0.1:54580/https2/x?reqId=7 [2026-02-05T10:18:14.980Z] Server localhost/127.0.0.1:54580 received: [2026-02-05T10:18:14.980Z] https://127.0.0.1:54580/https2/x?reqId=7: Hello there! [2026-02-05T10:18:14.980Z] Server: response sent for reqId=7 [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] Sending request:https://127.0.0.1:54580/https2/x?reqId=8 [2026-02-05T10:18:14.980Z] Server localhost/127.0.0.1:54580 received: [2026-02-05T10:18:14.980Z] https://127.0.0.1:54580/https2/x?reqId=8: Hello there! [2026-02-05T10:18:14.980Z] Server: response sent for reqId=8 [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] Sending request:https://127.0.0.1:54580/https2/x?reqId=9 [2026-02-05T10:18:14.980Z] Server localhost/127.0.0.1:54580 received: [2026-02-05T10:18:14.980Z] https://127.0.0.1:54580/https2/x?reqId=9: Hello there! [2026-02-05T10:18:14.980Z] Server: response sent for reqId=9 [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] Sending request:https://127.0.0.1:54580/https2/x?reqId=10 [2026-02-05T10:18:14.980Z] Server localhost/127.0.0.1:54580 received: [2026-02-05T10:18:14.980Z] https://127.0.0.1:54580/https2/x?reqId=10: Hello there! [2026-02-05T10:18:14.980Z] Server: response sent for reqId=10 [2026-02-05T10:18:14.980Z] [2026-02-05T10:18:14.980Z] Sending request:https://127.0.0.1:54580/https2/x?reqId=11 [2026-02-05T10:18:14.980Z] Got EXPECTED: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.980Z] Exception does not match expectation: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.980Z] java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.send(HttpClientImpl.java:917) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.HttpClientFacade.send(HttpClientFacade.java:133) [2026-02-05T10:18:14.980Z] at ConnectionFlowControlTest.test(ConnectionFlowControlTest.java:146) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:18:14.980Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:18:14.980Z] at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:132) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:599) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:174) [2026-02-05T10:18:14.980Z] at org.testng.internal.MethodRunner.runInSequence(MethodRunner.java:46) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestInvoker$MethodInvocationAgent.invoke(TestInvoker.java:822) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestInvoker.invokeTestMethods(TestInvoker.java:147) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:146) [2026-02-05T10:18:14.980Z] at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:128) [2026-02-05T10:18:14.980Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2026-02-05T10:18:14.980Z] at org.testng.TestRunner.privateRun(TestRunner.java:764) [2026-02-05T10:18:14.980Z] at org.testng.TestRunner.run(TestRunner.java:585) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunner.runTest(SuiteRunner.java:384) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:378) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunner.privateRun(SuiteRunner.java:337) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunner.run(SuiteRunner.java:286) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:53) [2026-02-05T10:18:14.980Z] at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:96) [2026-02-05T10:18:14.980Z] at org.testng.TestNG.runSuitesSequentially(TestNG.java:1218) [2026-02-05T10:18:14.980Z] at org.testng.TestNG.runSuitesLocally(TestNG.java:1140) [2026-02-05T10:18:14.980Z] at org.testng.TestNG.runSuites(TestNG.java:1069) [2026-02-05T10:18:14.980Z] at org.testng.TestNG.run(TestNG.java:1037) [2026-02-05T10:18:14.980Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:102) [2026-02-05T10:18:14.980Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:58) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:18:14.980Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:18:14.980Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:18:14.980Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2026-02-05T10:18:14.980Z] at java.base/java.lang.Thread.run(Thread.java:840) [2026-02-05T10:18:14.980Z] Caused by: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Stream.cancel(Stream.java:1410) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Http2Connection.putStream(Http2Connection.java:1432) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Stream.registerStream(Stream.java:1028) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Http2Connection.registerNewStream(Http2Connection.java:1533) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Http2Connection.sendFrame(Http2Connection.java:1557) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Stream.sendHeadersAsync(Stream.java:1000) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Exchange.lambda$responseAsyncImpl0$8(Exchange.java:564) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Exchange.checkFor407(Exchange.java:435) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Exchange.lambda$responseAsyncImpl0$9(Exchange.java:568) [2026-02-05T10:18:14.980Z] at java.base/java.util.concurrent.CompletableFuture.uniHandle(CompletableFuture.java:934) [2026-02-05T10:18:14.980Z] at java.base/java.util.concurrent.CompletableFuture.uniHandleStage(CompletableFuture.java:950) [2026-02-05T10:18:14.980Z] at java.base/java.util.concurrent.CompletableFuture.handle(CompletableFuture.java:2340) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsyncImpl0(Exchange.java:568) [2026-02-05T10:18:14.980Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsyncImpl(Exchange.java:411) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsync(Exchange.java:403) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.responseAsyncImpl(MultiExchange.java:430) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.lambda$responseAsync0$2(MultiExchange.java:350) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture$UniCompose.tryFire(CompletableFuture.java:1150) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1773) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$DelegatingExecutor.execute(HttpClientImpl.java:174) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.completeAsync(CompletableFuture.java:2673) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.responseAsync(MultiExchange.java:303) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.sendAsync(HttpClientImpl.java:991) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.send(HttpClientImpl.java:878) [2026-02-05T10:18:14.981Z] ... 35 more [2026-02-05T10:18:14.981Z] test ConnectionFlowControlTest.test("https://127.0.0.1:54580/https2/x"): failure [2026-02-05T10:18:14.981Z] java.lang.AssertionError: ProtocolException("protocol error: connection window exceeded") not found [2026-02-05T10:18:14.981Z] at ConnectionFlowControlTest.assertDetailMessage(ConnectionFlowControlTest.java:260) [2026-02-05T10:18:14.981Z] at ConnectionFlowControlTest.test(ConnectionFlowControlTest.java:223) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:18:14.981Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:18:14.981Z] at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:132) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:599) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:174) [2026-02-05T10:18:14.981Z] at org.testng.internal.MethodRunner.runInSequence(MethodRunner.java:46) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestInvoker$MethodInvocationAgent.invoke(TestInvoker.java:822) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestInvoker.invokeTestMethods(TestInvoker.java:147) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:146) [2026-02-05T10:18:14.981Z] at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:128) [2026-02-05T10:18:14.981Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2026-02-05T10:18:14.981Z] at org.testng.TestRunner.privateRun(TestRunner.java:764) [2026-02-05T10:18:14.981Z] at org.testng.TestRunner.run(TestRunner.java:585) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunner.runTest(SuiteRunner.java:384) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:378) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunner.privateRun(SuiteRunner.java:337) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunner.run(SuiteRunner.java:286) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:53) [2026-02-05T10:18:14.981Z] at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:96) [2026-02-05T10:18:14.981Z] at org.testng.TestNG.runSuitesSequentially(TestNG.java:1218) [2026-02-05T10:18:14.981Z] at org.testng.TestNG.runSuitesLocally(TestNG.java:1140) [2026-02-05T10:18:14.981Z] at org.testng.TestNG.runSuites(TestNG.java:1069) [2026-02-05T10:18:14.981Z] at org.testng.TestNG.run(TestNG.java:1037) [2026-02-05T10:18:14.981Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:102) [2026-02-05T10:18:14.981Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:58) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:18:14.981Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:18:14.981Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:18:14.981Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2026-02-05T10:18:14.981Z] at java.base/java.lang.Thread.run(Thread.java:840) [2026-02-05T10:18:14.981Z] Caused by: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.send(HttpClientImpl.java:917) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientFacade.send(HttpClientFacade.java:133) [2026-02-05T10:18:14.981Z] at ConnectionFlowControlTest.test(ConnectionFlowControlTest.java:146) [2026-02-05T10:18:14.981Z] ... 33 more [2026-02-05T10:18:14.981Z] Caused by: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Stream.cancel(Stream.java:1410) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Http2Connection.putStream(Http2Connection.java:1432) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Stream.registerStream(Stream.java:1028) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Http2Connection.registerNewStream(Http2Connection.java:1533) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Http2Connection.sendFrame(Http2Connection.java:1557) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Stream.sendHeadersAsync(Stream.java:1000) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.lambda$responseAsyncImpl0$8(Exchange.java:564) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.checkFor407(Exchange.java:435) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.lambda$responseAsyncImpl0$9(Exchange.java:568) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.uniHandle(CompletableFuture.java:934) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.uniHandleStage(CompletableFuture.java:950) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.handle(CompletableFuture.java:2340) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsyncImpl0(Exchange.java:568) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsyncImpl(Exchange.java:411) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.Exchange.responseAsync(Exchange.java:403) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.responseAsyncImpl(MultiExchange.java:430) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.lambda$responseAsync0$2(MultiExchange.java:350) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture$UniCompose.tryFire(CompletableFuture.java:1150) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1773) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl$DelegatingExecutor.execute(HttpClientImpl.java:174) [2026-02-05T10:18:14.981Z] at java.base/java.util.concurrent.CompletableFuture.completeAsync(CompletableFuture.java:2673) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.MultiExchange.responseAsync(MultiExchange.java:303) [2026-02-05T10:18:14.981Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.sendAsync(HttpClientImpl.java:991) [2026-02-05T10:18:14.982Z] at java.net.http/jdk.internal.net.http.HttpClientImpl.send(HttpClientImpl.java:878) [2026-02-05T10:18:14.982Z] ... 35 more [2026-02-05T10:18:14.982Z] Http2TestServerImpl: stop [2026-02-05T10:18:14.982Z] Http2TestServerImpl: stop [2026-02-05T10:18:14.982Z] config ConnectionFlowControlTest.teardown(): success [2026-02-05T10:18:14.982Z] [2026-02-05T10:18:14.982Z] =============================================== [2026-02-05T10:18:14.982Z] java/net/httpclient/http2/ConnectionFlowControlTest.java [2026-02-05T10:18:14.982Z] Total tests run: 2, Passes: 1, Failures: 1, Skips: 0 [2026-02-05T10:18:14.982Z] =============================================== [2026-02-05T10:18:14.982Z] [2026-02-05T10:18:14.982Z] STDERR: [2026-02-05T10:18:14.982Z] TestServer(1): accepting connections [2026-02-05T10:18:14.982Z] TestServer(2): accepting connections [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [18ms] HttpClientImpl(1) proxySelector is sun.net.spi.DefaultProxySelector@3dbbc4bf (user-supplied=false) [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [54ms] HttpClientImpl(1) ClientImpl (async) send http://127.0.0.1:54579/http2/x?reqId=1 POST [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [80ms] Exchange establishing exchange for http://127.0.0.1:54579/http2/x?reqId=1 POST, [2026-02-05T10:18:14.982Z] proxy=null [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [109ms] Http2ClientImpl not found in connection pool [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [109ms] ExchangeImpl get: Trying to get HTTP/2 connection [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [109ms] ExchangeImpl handling HTTP/2 connection creation result [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [109ms] ExchangeImpl new Http1Exchange, try to upgrade [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [118ms] PlainHttpConnection(?) Initial receive buffer size is: 65536 [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [118ms] PlainHttpConnection(?) Initial send buffer size is: 65536 [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [126ms] Exchange checkFor407: all clear [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [127ms] Http1Exchange Sending headers only [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [131ms] Http1AsyncReceiver(SocketTube(1)) Subscribed pending jdk.internal.net.http.Http1Response$HeadersReader@4e9f5949 queue.isEmpty: true [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [138ms] Http1AsyncReceiver(SocketTube(1)) delegate is now jdk.internal.net.http.Http1Response$HeadersReader@4e9f5949, demand=1, canRequestMore=true, queue.isEmpty=true [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [138ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 1 [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [142ms] Http1AsyncReceiver(SocketTube(1)) checkRequestMore: canRequestMore=true, hasDemand=true [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [142ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 1 [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [142ms] Http1AsyncReceiver(SocketTube(1)) checkRequestMore: canRequestMore=true, hasDemand=true [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [142ms] Http1Exchange response created in advance [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [142ms] Http1Exchange initiating connect async [2026-02-05T10:18:14.982Z] TestServer(1): connection accepted [2026-02-05T10:18:14.982Z] TestServer(1): stopping is: false [2026-02-05T10:18:14.982Z] TestServer(1): accepting connections [2026-02-05T10:18:14.982Z] DEBUG: [MainThread] [147ms] PlainHttpConnection(SocketTube(1)) registering connect event [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [148ms] SelectorAttachment Registering jdk.internal.net.http.PlainHttpConnection$ConnectEvent@5a92766 for 8 (true) [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [151ms] PlainHttpConnection(SocketTube(1)) ConnectEvent: finishing connect [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [151ms] PlainHttpConnection(SocketTube(1)) ConnectEvent: connect finished: true, cancelled: false, Local addr: /127.0.0.1:54581 [2026-02-05T10:18:14.982Z] TestServer(1): creating connection [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [176ms] PlainHttpConnection(SocketTube(1)) finishConnect, setting connected=true [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [177ms] Http1Exchange SocketTube(1) connecting flows [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [177ms] SocketTube(1) connecting flows [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [177ms] SocketTube(1) read publisher got subscriber [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [177ms] SocketTube(1) registering subscribe event [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [177ms] SocketTube(1) subscribe event raised [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] SocketTube(1) handling pending subscription for jdk.internal.net.http.Http1AsyncReceiver$Http1TubeSubscriber@70376e98 [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] SocketTube(1) read demand reset to 0 [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] SocketTube(1) calling onSubscribe [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] Http1AsyncReceiver(SocketTube(1)) Received onSubscribed from upstream [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] SocketTube(1) onSubscribe called [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [178ms] SocketTube(1) pending subscriber subscribed [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [178ms] SocketTube(1) leaving read.subscribe: Reading: [ops=0, demand=0, stopped=false], Writing: [ops=0, demand=0] [2026-02-05T10:18:14.982Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=54581,localport=54579] [2026-02-05T10:18:14.982Z] TestServer(1): starting connection [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [211ms] Http1Publisher(SocketTube(1)) got subscriber: SocketTube(1) [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [211ms] SocketTube(1) subscribed for writing [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [211ms] SocketTube(1) write: registering startSubscription event [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [211ms] SocketTube(1) write: starting subscription [2026-02-05T10:18:14.982Z] DEBUG: [HttpClient-1-SelectorManager] [211ms] SocketTube(1) write: offloading requestMore [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [212ms] Http1Exchange requestAction.headers [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-2] [395ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 1 [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-2] [395ms] Http1AsyncReceiver(SocketTube(1)) checkRequestMore: canRequestMore=true, hasDemand=true [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-2] [395ms] Http1AsyncReceiver(SocketTube(1)) Http1TubeSubscriber: requesting one more from upstream [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-2] [396ms] SocketTube(1) got some demand for reading [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-2] [396ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [401ms] Http1Exchange setting outgoing with headers [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [402ms] Http1Exchange appending to outgoing DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=237 cap=237]], throwable=null] [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [402ms] Http1Publisher(SocketTube(1)) WriteTask [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-1] [405ms] Http1Publisher(SocketTube(1)) hasOutgoing = true, demand = 0 [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-3] [408ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-3] [408ms] Http1Publisher(SocketTube(1)) subscription request(1), demand=1 [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-3] [408ms] Http1Publisher(SocketTube(1)) WriteTask [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-3] [408ms] Http1Publisher(SocketTube(1)) hasOutgoing = true, demand = 1 [2026-02-05T10:18:14.982Z] DEBUG: [pool-1-thread-3] [409ms] Http1Exchange initiating completion of headersSentCF [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [409ms] Exchange checkFor407: all clear [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [409ms] Exchange sendRequestBody [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [409ms] Http1Exchange sendBodyAsync [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [410ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (true) [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [411ms] Http1Exchange bodySubscriber is class jdk.internal.net.http.Http1Request$FixedContentSubscriber [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [412ms] SocketTube(1) leaving request(1): Reading: [ops=1, demand=1, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [418ms] Http1Exchange requesting more request body from the subscriber [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [418ms] jdk.internal.net.http.Http1Request@5535e01b Http1BodySubscriber requesting 1, from jdk.internal.net.http.PullPublisher$Subscription@5a2feb02 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [419ms] jdk.internal.net.http.Http1Request@5535e01b onNext [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [419ms] Http1Exchange appending to outgoing DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=12 cap=12]], throwable=null] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [419ms] jdk.internal.net.http.Http1Request@5535e01b onComplete [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [419ms] Http1Exchange appending to outgoing DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=0 cap=0]], throwable=null] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [424ms] Http1Publisher(SocketTube(1)) outgoing: DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=237 cap=237]], throwable=null] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [424ms] Http1Publisher(SocketTube(1)) onNext with 237 bytes [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [424ms] SocketTube(1) trying to write: 237 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [426ms] SocketTube(1) wrote: 237 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [426ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [426ms] Http1Publisher(SocketTube(1)) subscription request(1), demand=1 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [426ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=1, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [426ms] SocketTube(1) leaving w.onNext Reading: [ops=1, demand=1, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [428ms] Http1Exchange requesting more request body from the subscriber [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [428ms] jdk.internal.net.http.Http1Request@5535e01b Http1BodySubscriber requesting 1, from jdk.internal.net.http.PullPublisher$Subscription@5a2feb02 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [428ms] Http1Publisher(SocketTube(1)) outgoing: DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=12 cap=12]], throwable=null] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [428ms] Http1Publisher(SocketTube(1)) onNext with 12 bytes [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [428ms] SocketTube(1) trying to write: 12 [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [430ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [430ms] SocketTube(1) read bytes: 71 [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [430ms] Http1AsyncReceiver(SocketTube(1)) Putting 71 bytes into the queue [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [430ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [431ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.983Z] DEBUG: [HttpClient-1-SelectorManager] [431ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [431ms] Http1AsyncReceiver(SocketTube(1)) Got 71 bytes for delegate jdk.internal.net.http.Http1Response$HeadersReader@4e9f5949 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [431ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 1 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [432ms] Http1AsyncReceiver(SocketTube(1)) Forwarding 71 bytes to delegate jdk.internal.net.http.Http1Response$HeadersReader@4e9f5949 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [438ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Sending 71/71 bytes to header parser [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [440ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Parsing headers completed. bytes=71 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [440ms] Http1AsyncReceiver(SocketTube(1)) Unsubscribed jdk.internal.net.http.Http1Response$HeadersReader@4e9f5949 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-2] [459ms] Http1AsyncReceiver(SocketTube(1)) Delegate done: 0 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [460ms] SocketTube(1) wrote: 12 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [460ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [461ms] Http1Publisher(SocketTube(1)) subscription request(1), demand=1 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [461ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [461ms] SocketTube(1) leaving w.onNext Reading: [ops=1, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [462ms] Http1Exchange initiating completion of bodySentCF [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [462ms] Http1Exchange sendBodyAsync completed successfully [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [462ms] Http1Exchange reading headers [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [463ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Reading Headers: (remaining: 0) READING_BODY [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [463ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) First time around [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [463ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) headersReader is already completed [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [465ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Reading Headers: creating Response object; state is now READING_BODY [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [465ms] Http1Exchange getResponseAsync completed successfully [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [466ms] Exchange Upgrading async PlainHttpConnection: HttpConnection: java.nio.channels.SocketChannel[connected local=/127.0.0.1:54581 remote=/127.0.0.1:54579] [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [468ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) readBody: return2Cache: false [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [478ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Operation started: incrementing ref count for jdk.internal.net.http.HttpClientImpl@5c3853ba(1) [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [485ms] Http1AsyncReceiver(SocketTube(1)) Subscribed pending jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a queue.isEmpty: false [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [487ms] PlainHttpConnection(SocketTube(1))/ResponseContent/FixedLengthBodyParser length=0, onSubscribe: jdk.internal.net.http.Http1Exchange$Http1ResponseBodySubscriber [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [487ms] HttpClientImpl(1) body subscriber registered: 1 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [487ms] Http1AsyncReceiver(SocketTube(1)) cleared [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] HttpClientImpl(1) body subscriber unregistered: 0 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) delegate is now jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a, demand=9223372036854775807, canRequestMore=false, queue.isEmpty=false [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Got 0 bytes for delegate jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 9223372036854775807 [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Forwarding 0 bytes to delegate jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a [2026-02-05T10:18:14.983Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Delegate done: 0 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Got 0 bytes for delegate jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) downstream subscription demand is 9223372036854775807 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Forwarding 0 bytes to delegate jdk.internal.net.http.Http1Response$BodyReader@64a0b2b8/parser=jdk.internal.net.http.ResponseContent$FixedLengthBodyParser@6a72b49a [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [488ms] Http1AsyncReceiver(SocketTube(1)) Delegate done: 0 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [489ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Finished reading body: READING_BODY [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [489ms] Http1Response(id=1, PlainHttpConnection(SocketTube(1))) Operation finished: decrementing ref count for jdk.internal.net.http.HttpClientImpl@5c3853ba(1) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [494ms] Exchange Ignored body [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [618ms] Http2Connection(SocketTube(1)):WindowUpdateSender(stream: 0) maxFrameSize=16384, initWindowSize=65535, limit=32768 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [629ms] Http2Connection(SocketTube(1)):WindowUpdateSender(stream: ?) maxFrameSize=16384, initWindowSize=16384, limit=0 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [629ms] Http2Connection(SocketTube(1)) Opened stream 1 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [631ms] Http2Connection(SocketTube(1))/Stream(1) Stream 1 registered (cancelled: false, registerIfCancelled: true) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [631ms] Http2Connection(SocketTube(1))/Stream(1) requestSent: streamid=1 but response not received [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [631ms] SocketTube(1) connecting flows [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [631ms] SocketTube(1) read publisher got subscriber [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [631ms] SocketTube(1) registering subscribe event [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [631ms] SocketTube(1) subscribe event raised [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) handling pending subscription for jdk.internal.net.http.Http2Connection$Http2TubeSubscriber@71b81935 [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] Http1AsyncReceiver(SocketTube(1)) Http1TubeSubscriber: dropSubscription [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) read demand reset to 0 [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) calling onSubscribe [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] Http2Connection(SocketTube(1)) onSubscribe: requesting Long.MAX_VALUE for reading [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) got some demand for reading [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) leaving request(9223372036854775807): Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) onSubscribe called [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [632ms] SocketTube(1) pending subscriber subscribed [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [632ms] SocketTube(1) leaving read.subscribe: Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [632ms] SocketTube(1) subscribed for writing [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [633ms] SocketTube(1) write: resetting demand to 0 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [633ms] SocketTube(1) write: registering startSubscription event [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [633ms] SocketTube(1) write: starting subscription [2026-02-05T10:18:14.984Z] DEBUG: [HttpClient-1-SelectorManager] [633ms] SocketTube(1) write: offloading requestMore [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-2] [633ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-2] [633ms] PlainHttpConnection(SocketTube(1)) HttpPublisher: got request of 1 from SocketTube(1) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-2] [633ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [635ms] PlainHttpConnection(SocketTube(1)) added 69 bytes in the priority write queue [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [635ms] PlainHttpConnection(SocketTube(1)) signalling the publisher of the write queue [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [640ms] PlainHttpConnection(SocketTube(1)) HttpPublisher: sending 69 bytes (1 buffers) to SocketTube(1) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [640ms] SocketTube(1) trying to write: 69 [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [641ms] FramesDecoder decodes: 9 [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [641ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [641ms] FramesDecoder Not enough data to parse frame body, needs: 36, has: 0 [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [642ms] FramesDecoder decodes: 36 [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [642ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.984Z] DEBUG: [TestServer(1)-pool] [642ms] FramesDecoder Got frame: SETTINGS: length=36, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=0 MAX_CONCURRENT_STREAMS=0 INITIAL_WINDOW_SIZE=16384 MAX_FRAME_SIZE=16384 MAX_HEADER_LIST_SIZE=393216 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [645ms] SocketTube(1) wrote: 69 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [645ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.984Z] TestServer(1): connection started [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [647ms] PlainHttpConnection(SocketTube(1)) HttpPublisher: got request of 1 from SocketTube(1) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [647ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [647ms] SocketTube(1) leaving w.onNext Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [647ms] Http2Connection(SocketTube(1)) Triggering processing of buffered data after sending connection preface [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [650ms] Http2Connection(SocketTube(1)) onNext: got 0 bytes in 1 buffers [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [650ms] Http2Connection(SocketTube(1)) sending 0 to Http2Connection.asyncReceive [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [650ms] Http1AsyncReceiver(SocketTube(1)) stopping [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [651ms] Http1Exchange asyncReceiver finished (failed=null) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [651ms] Http1AsyncReceiver(SocketTube(1)) Flushing 0 bytes from queue into initial buffer (remaining=0, free=0) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [654ms] Http1AsyncReceiver(SocketTube(1)) Initial buffer now has 0 pos=0 limit=0 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [654ms] Http2Connection(SocketTube(1)) H2 Received EMPTY_TRIGGER [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [654ms] Http2Connection(SocketTube(1)) H2 processed buffered data [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [654ms] Http2ClientImpl offering to the connection pool: Http2Connection(SocketTube(1)) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [654ms] Http2ClientImpl put in the connection pool: Http2Connection(SocketTube(1)) [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [655ms] Http1AsyncReceiver(SocketTube(1)) cleared [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [655ms] Exchange Getting response async streamid: 1 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [655ms] Http1Publisher(SocketTube(1)) outgoing: DataPair [data=[java.nio.HeapByteBuffer[pos=0 lim=0 cap=0]], throwable=null] [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [655ms] Http1Publisher(SocketTube(1)) completed, stopping jdk.internal.net.http.common.SequentialScheduler@2368f1e1 [2026-02-05T10:18:14.984Z] DEBUG: [pool-1-thread-3] [655ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=9223372036854775807, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.984Z] DEBUG: [readLoop] [657ms] FramesDecoder decodes: 9 [2026-02-05T10:18:14.984Z] DEBUG: [readLoop] [657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.984Z] DEBUG: [readLoop] [657ms] FramesDecoder Not enough data to parse frame body, needs: 36, has: 0 [2026-02-05T10:18:14.985Z] DEBUG: [readLoop] [657ms] FramesDecoder decodes: 36 [2026-02-05T10:18:14.985Z] DEBUG: [readLoop] [657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.985Z] DEBUG: [readLoop] [657ms] FramesDecoder Got frame: SETTINGS: length=36, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=16384 ENABLE_PUSH=0 MAX_CONCURRENT_STREAMS=0 INITIAL_WINDOW_SIZE=16384 MAX_FRAME_SIZE=16384 MAX_HEADER_LIST_SIZE=393216 [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [659ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.985Z] TestServer(1): POST /http2/x?reqId=1 [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [659ms] SocketTube(1) read bytes: 39 [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [660ms] Http2Connection(SocketTube(1)) onNext: got 39 bytes in 1 buffers [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [660ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [660ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.985Z] DEBUG: [HttpClient-1-SelectorManager] [660ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775806, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] Http2Connection(SocketTube(1)) sending 39 to Http2Connection.asyncReceive [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] Http2Connection(SocketTube(1)) H2 Receiving(1): 39 [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] Http2Connection(SocketTube(1)) Processing 39 [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] FramesDecoder decodes: 39 [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [660ms] FramesDecoder Got frame: SETTINGS: length=30, streamid=0, flags=0 Settings: HEADER_TABLE_SIZE=4096 ENABLE_PUSH=1 MAX_CONCURRENT_STREAMS=2147483647 INITIAL_WINDOW_SIZE=65535 MAX_FRAME_SIZE=16384 [2026-02-05T10:18:14.985Z] DEBUG: [pool-1-thread-3] [661ms] PlainHttpConnection(SocketTube(1)) added 9 bytes to the write queue [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [661ms] PlainHttpConnection(SocketTube(1)) signalling the publisher of the write queue [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [661ms] PlainHttpConnection(SocketTube(1)) HttpPublisher: sending 9 bytes (1 buffers) to SocketTube(1) [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [661ms] SocketTube(1) trying to write: 9 [2026-02-05T10:18:14.987Z] DEBUG: [readLoop] [661ms] FramesDecoder decodes: 9 [2026-02-05T10:18:14.987Z] DEBUG: [readLoop] [661ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [readLoop] [661ms] FramesDecoder Got frame: SETTINGS: length=0, streamid=0, flags=ACK Settings: [2026-02-05T10:18:14.987Z] DEBUG: [readLoop] [661ms] FramesDecoder decodes: 0 [2026-02-05T10:18:14.987Z] DEBUG: [readLoop] [661ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] SocketTube(1) read bytes: 9 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] Http2Connection(SocketTube(1)) onNext: got 9 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [662ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775805, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] SocketTube(1) wrote: 9 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] SocketTube(1) write: requesting more... [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] PlainHttpConnection(SocketTube(1)) HttpPublisher: got request of 1 from SocketTube(1) [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] SocketTube(1) leaving requestMore: Reading: [ops=1, demand=9223372036854775805, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] SocketTube(1) leaving w.onNext Reading: [ops=1, demand=9223372036854775805, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] Http2Connection(SocketTube(1)) H2 processed(1) [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] Http2Connection(SocketTube(1)) sending 9 to Http2Connection.asyncReceive [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] Http2Connection(SocketTube(1)) H2 Receiving(2): 9 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [667ms] Http2Connection(SocketTube(1)) Processing 9 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [668ms] FramesDecoder decodes: 9 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [668ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [668ms] FramesDecoder Got frame: SETTINGS: length=0, streamid=0, flags=ACK Settings: [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [668ms] Http2Connection(SocketTube(1)) H2 processed(2) [2026-02-05T10:18:14.987Z] TestServer(1): creating input stream for stream 1 [2026-02-05T10:18:14.987Z] TestServer(1): Using handler for: /http2/ [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [677ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [677ms] SocketTube(1) read bytes: 13 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [677ms] Http2Connection(SocketTube(1)) onNext: got 13 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [677ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [678ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [678ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775804, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [678ms] Http2Connection(SocketTube(1)) sending 13 to Http2Connection.asyncReceive [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [678ms] Http2Connection(SocketTube(1)) H2 Receiving(3): 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [678ms] Http2Connection(SocketTube(1)) Processing 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [679ms] FramesDecoder decodes: 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [679ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [679ms] FramesDecoder Got frame: WINDOW_UPDATE: length=4, streamid=1, flags=0 WindowUpdate: 12 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [679ms] Http2Connection(SocketTube(1))/Stream(1) incoming: WINDOW_UPDATE: length=4, streamid=1, flags=0 WindowUpdate: 12 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [679ms] WindowController Stream 1 window size is now 65547 (amount added 12) [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [680ms] Http2Connection(SocketTube(1)) H2 processed(3) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] SocketTube(1) read bytes: 13 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] Http2Connection(SocketTube(1)) onNext: got 13 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [680ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775803, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [680ms] Http2Connection(SocketTube(1)) sending 13 to Http2Connection.asyncReceive [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [682ms] Http2Connection(SocketTube(1)) H2 Receiving(4): 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] Http2Connection(SocketTube(1)) Processing 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] FramesDecoder decodes: 13 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] FramesDecoder Got frame: WINDOW_UPDATE: length=4, streamid=0, flags=0 WindowUpdate: 12 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] WindowController Connection window size is now 65547 (amount added 12) [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [684ms] Http2Connection(SocketTube(1)) H2 processed(4) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] SocketTube(1) read bytes: 60 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] Http2Connection(SocketTube(1)) onNext: got 60 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [687ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775802, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] Http2Connection(SocketTube(1)) sending 60 to Http2Connection.asyncReceive [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] Http2Connection(SocketTube(1)) H2 Receiving(5): 60 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] Http2Connection(SocketTube(1)) Processing 60 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] FramesDecoder decodes: 60 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [689ms] FramesDecoder Got frame: HEADERS: length=51, streamid=1, flags=END_HEADERS [2026-02-05T10:18:14.987Z] Sent response headers 200 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [690ms] Http2Connection(SocketTube(1))/Stream(1) incoming: HEADERS: length=51, streamid=1, flags=END_HEADERS [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [691ms] Http2Connection(SocketTube(1))/Stream(1) Response builder cleared, ready to receive new headers. [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] SocketTube(1) read bytes: 21 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] Http2Connection(SocketTube(1)) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [692ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775801, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 0 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] SocketTube(1) read bytes: 21 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] Http2Connection(SocketTube(1)) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] SocketTube(1) resuming read event [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@61eaa142 for 1 (false) [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [693ms] SocketTube(1) leaving read() loop after onNext: Reading: [ops=1, demand=9223372036854775800, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [693ms] Http2Connection(SocketTube(1))/Stream(1) Completing responseCF(0) with response headers [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [694ms] Http2Connection(SocketTube(1))/Stream(1) Getting BodySubscriber for: (POST http://127.0.0.1:54579/http2/x?reqId=1) 200 HTTP_2 Local port: 54581 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [696ms] Http2Connection(SocketTube(1))/Stream(1) subscribing user subscriber [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [696ms] HttpClientImpl(1) body subscriber registered: 1 [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [696ms] HttpResponseInputStream onSubscribe called [2026-02-05T10:18:14.987Z] DEBUG: [pool-1-thread-3] [697ms] HttpResponseInputStream onSubscribe: requesting 1 [2026-02-05T10:18:14.987Z] DEBUG: [HttpClient-1-SelectorManager] [699ms] SelectorAttachment Registering jdk.internal.ne [2026-02-05T10:18:14.987Z] [2026-02-05T10:18:14.987Z] ... [2026-02-05T10:18:14.987Z] Output overflow: [2026-02-05T10:18:14.987Z] JT Harness has limited the test output to the text [2026-02-05T10:18:14.987Z] at the beginning and the end, so that you can see how the [2026-02-05T10:18:14.987Z] test began, and how it completed. [2026-02-05T10:18:14.987Z] [2026-02-05T10:18:14.987Z] If you need to see more of the output from the test, [2026-02-05T10:18:14.987Z] set the system property javatest.maxOutputSize to a higher [2026-02-05T10:18:14.987Z] value. The current value is 100000 [2026-02-05T10:18:14.987Z] ... [2026-02-05T10:18:14.987Z] [2026-02-05T10:18:14.987Z] ] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5461): 21 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5461) [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapping: 1160 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 1160 into buffer of 64 remaining to decode: 1101 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.987Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.987Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770346 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5462): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5462) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapping: 1101 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 1101 into buffer of 64 remaining to decode: 1042 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770345 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5463): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5463) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapping: 1042 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 1042 into buffer of 64 remaining to decode: 983 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770344 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5464): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5464) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 656ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 983 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 983 into buffer of 64 remaining to decode: 924 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770343 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5465): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5465) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 924 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 924 into buffer of 64 remaining to decode: 865 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770342 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5466): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5466) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 865 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 865 into buffer of 64 remaining to decode: 806 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770341 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5467): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5467) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 806 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 806 into buffer of 64 remaining to decode: 747 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.988Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770340 cancelled = false [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5468): 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5468) [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 747 [2026-02-05T10:18:14.988Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 747 into buffer of 64 remaining to decode: 688 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770339 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5469): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5469) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 688 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 688 into buffer of 64 remaining to decode: 629 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770338 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5470): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5470) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 629 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 629 into buffer of 64 remaining to decode: 570 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770337 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5471): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5471) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 570 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 570 into buffer of 64 remaining to decode: 511 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770336 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5472): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5472) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 511 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 511 into buffer of 64 remaining to decode: 452 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770335 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5473): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5473) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 452 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 452 into buffer of 64 remaining to decode: 393 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770334 cancelled = false [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5474): 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5474) [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapping: 393 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 393 into buffer of 64 remaining to decode: 334 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.989Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 657ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.989Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770333 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5475): 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5475) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapping: 334 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 334 into buffer of 64 remaining to decode: 275 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.990Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770332 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5476): 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5476) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapping: 275 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 275 into buffer of 64 remaining to decode: 216 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.990Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770331 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5477): 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5477) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapping: 216 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 216 into buffer of 64 remaining to decode: 157 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.990Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770330 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5478): 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5478) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapping: 157 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Decoded 21 bytes out of 157 into buffer of 64 remaining to decode: 98 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.990Z] bytesConsumed = 59 bytesProduced = 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 59 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 21 to outputQ queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770329 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 21 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 21 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 21 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5479): 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 21 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=12, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5479) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapping: 98 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Decoded 13 bytes out of 98 into buffer of 64 remaining to decode: 47 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.990Z] bytesConsumed = 51 bytesProduced = 13 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 51 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) sending 13 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) Adding 13 to outputQ queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 9223372036854770328 cancelled = false [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Reader(SocketTube(3)) DownstreamPusher: Pushing 13 bytes downstream [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) onNext: got 13 bytes in 1 buffers [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) sending 13 to Http2Connection.asyncReceive [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) H2 Receiving(5480): 13 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Processing 13 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder decodes: 13 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder Got frame: DATA: length=4, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) incoming: DATA: length=4, streamid=7, flags=0 [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] FramesDecoder closed protocol error: connection window exceeded, ignoring 0 bytes [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] Http2Connection(SSLTube(SocketTube(3))) Sending GOAWAY due to java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 17 bytes to the write queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: sending 17 bytes (1 buffers) to SSLTube(SocketTube(3)) [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSLTube(SocketTube(3)) sending 1 buffers to SSL flow delegate [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Writer(SocketTube(3)) onNext [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Writer(SocketTube(3)) added 1 (17 bytes) to the writeList [2026-02-05T10:18:14.990Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Writer(SocketTube(3)) processData, writeList remaining:17, hsTriggered:false, needWrap:false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 658ms] SSL Writer(SocketTube(3)) wrapping 17 bytes [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) SSLResult: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 17 bytesProduced = 55 sequenceNumber = 12 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) OK => produced: 55 bytes into 56, not wrapped: 0 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) wrapBuffer returned Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 17 bytesProduced = 55 sequenceNumber = 12 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) Sending 55 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) Adding 55 to outputQ queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 1 cancelled = false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) DownstreamPusher: Pushing 55 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SocketTube(3) trying to write: 55 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SocketTube(3) wrote: 55 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SocketTube(3) write: requesting more... [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SocketTube(3) leaving requestMore: Reading: [ops=1, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SocketTube(3) leaving w.onNext Reading: [ops=1, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) requesting 1 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSLTube(SocketTube(3)) request: n=1 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: got request of 1 from SSLTube(SocketTube(3)) [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] SSL Writer(SocketTube(3)) DownstreamPusher: queue empty, downstreamSubscription: SubscriptionBase: window = 1 cancelled = false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] Http2Connection(SSLTube(SocketTube(3))) Shutting down h2c (state=half-closed-local): java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 659ms] Http2ClientImpl removing from the connection pool: Http2Connection(SSLTube(SocketTube(3))) [2026-02-05T10:18:14.991Z] DEBUG: [MainThread] [5s 641ms] Http2ClientImpl found connection in the pool: Http2Connection(SSLTube(SocketTube(3))) [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder decodes: 9 [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder Not enough data to parse frame body, needs: 8, has: 0 [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder decodes: 8 [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder Tail size is now: 0, current= [2026-02-05T10:18:14.991Z] DEBUG: [readLoop] [5s 659ms] FramesDecoder Got frame: GOAWAY: length=8, streamid=0, flags=0 Error: Flow control error lastStreamId=0, Debugdata: [2026-02-05T10:18:14.991Z] TestServer(2): Closing connection: GOAWAY: length=8, streamid=0, flags=0 Error: Flow control error lastStreamId=0, Debugdata: [2026-02-05T10:18:14.991Z] TestServer(2): Server connection to /127.0.0.1:54583 stopping. 4 streams [2026-02-05T10:18:14.991Z] TestServer(2): Sending GOAWAY frame GOAWAY: length=8, streamid=0, flags=0 Error: Not an error lastStreamId=7, Debugdata: from server connection jdk.httpclient.test.lib.http2.Http2TestServerConnection@3d24f708 [2026-02-05T10:18:14.991Z] DEBUG: [HttpClient-2-SelectorManager] [5s 661ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@5969b3d1 for 0 (false) [2026-02-05T10:18:14.991Z] DEBUG: [HttpClient-2-SelectorManager] [5s 661ms] SocketTube(3) no more demand for reading [2026-02-05T10:18:14.991Z] DEBUG: [HttpClient-2-SelectorManager] [5s 661ms] SocketTube(3) leaving read() loop with no demand Reading: [ops=0, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] Http2ClientImpl removed from the connection pool: Http2Connection(SSLTube(SocketTube(3))) [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(1) calling subscriber.onError: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] HttpClientImpl(2) body subscriber unregistered: 3 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] HttpResponseInputStream onError called: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(1) cancelling stream 1: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(1) stream 1 closing due to java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 13 bytes to the write queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: sending 13 bytes (1 buffers) to SSLTube(SocketTube(3)) [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSLTube(SocketTube(3)) sending 1 buffers to SSL flow delegate [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) onNext [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) added 1 (13 bytes) to the writeList [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) processData, writeList remaining:13, hsTriggered:false, needWrap:false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) wrapping 13 bytes [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) SSLResult: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 13 bytesProduced = 51 sequenceNumber = 13 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) OK => produced: 51 bytes into 56, not wrapped: 0 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) wrapBuffer returned Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 13 bytesProduced = 51 sequenceNumber = 13 [2026-02-05T10:18:14.991Z] DEBUG: [MainThread] [5s 661ms] ExchangeImpl get: Trying to get HTTP/2 connection [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 661ms] SSL Writer(SocketTube(3)) Sending 51 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) Adding 51 to outputQ queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 1 cancelled = false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) DownstreamPusher: Pushing 51 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SocketTube(3) trying to write: 51 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SocketTube(3) wrote: 51 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SocketTube(3) write: requesting more... [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SocketTube(3) leaving requestMore: Reading: [ops=0, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SocketTube(3) leaving w.onNext Reading: [ops=0, demand=0, stopped=false], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) upstreamWindowUpdate, downstreamQueueSize:0, upstreamWindow:0 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) requesting 1 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSLTube(SocketTube(3)) request: n=1 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: got request of 1 from SSLTube(SocketTube(3)) [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) DownstreamPusher: queue empty, downstreamSubscription: SubscriptionBase: window = 1 cancelled = false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] Http2Connection(SSLTube(SocketTube(3))) Closed stream 1 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(3) calling subscriber.onError: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] HttpClientImpl(2) body subscriber unregistered: 2 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] HttpResponseInputStream onError called: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(3) cancelling stream 3: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(3) stream 3 closing due to java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 13 bytes to the write queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: sending 13 bytes (1 buffers) to SSLTube(SocketTube(3)) [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSLTube(SocketTube(3)) sending 1 buffers to SSL flow delegate [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) onNext [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) added 1 (13 bytes) to the writeList [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) processData, writeList remaining:13, hsTriggered:false, needWrap:false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 663ms] SSL Writer(SocketTube(3)) wrapping 13 bytes [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) SSLResult: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 13 bytesProduced = 51 sequenceNumber = 14 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) OK => produced: 51 bytes into 56, not wrapped: 0 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) wrapBuffer returned Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.991Z] bytesConsumed = 13 bytesProduced = 51 sequenceNumber = 14 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) Sending 51 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) Adding 51 to outputQ queue [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) pushScheduler is alive [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) DownstreamPusher: queue not empty, downstreamSubscription: SubscriptionBase: window = 1 cancelled = false [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SSL Writer(SocketTube(3)) DownstreamPusher: Pushing 51 bytes downstream [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SocketTube(3) trying to write: 51 [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SocketTube(3) write error: java.io.IOException: An established connection was aborted by the software in your host machine [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SocketTube(3) error signalled java.io.IOException: An established connection was aborted by the software in your host machine [2026-02-05T10:18:14.991Z] DEBUG: [pool-2-thread-1] [5s 664ms] SocketTube(3) pausing read event [2026-02-05T10:18:14.991Z] DEBUG: [HttpClient-2-SelectorManager] [5s 665ms] SelectorAttachment Registering jdk.internal.net.http.SocketTube$InternalReadPublisher$ReadEvent@5969b3d1 for 0 (false) [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) Sending error java.io.IOException: An established connection was aborted by the software in your host machine to subscriber READER: SubscriberWrapper: upstreamCompleted: false upstreamWindow: 0 downstreamCompleted: false completionAcknowledged: false outputQ size: 0 cf: jdk.internal.net.http.common.MinimalFuture@6c4ba09a[Completed normally] (id=320) downstreamSubscription: SubscriptionBase: window = 9223372036854770327 cancelled = true downstreamSubscriber: SSLSubscriberWrapper[SSLTube(SocketTube(3)), delegate: DelegateWrapper[subscribedCalled: true, subscribedDone: true, completed: false, error: null]: jdk.internal.net.http.Http2Connection$Http2TubeSubscriber@133f7fc3, getALPN: jdk.internal.net.http.common.MinimalFuture@207c0eaa[Completed normally] (id=326), onCompleteReceived: false, onError: null], readBuf: java.nio.HeapByteBuffer[pos=6777 lim=6824 cap=65536], count: 115113, scheduler: running, status: OK, handshakeState: 0, engine: NOT_HANDSHAKING [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) Stopping read scheduler [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) leaving read() loop with error: Reading: [ops=0, demand=0, stopped=true], Writing: [ops=0, demand=1] [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) Read scheduler stopped [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) write: cancel [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 668ms] SocketTube(3) write: resetting demand to 0 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] AsyncSSLConnection(SSLTube(SocketTube(3))) HttpPublisher: cancelled by SSLTube(SocketTube(3)) [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] SSL Reader(SocketTube(3)) stop [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] SSL Writer(SocketTube(3)) stop [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] SocketTube(3) leaving w.onNext Reading: [ops=0, demand=0, stopped=true], Writing: [ops=0, demand=0] [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3))) Closed stream 3 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(5) calling subscriber.onError: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] HttpClientImpl(2) body subscriber unregistered: 1 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] HttpResponseInputStream onError called: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(5) cancelling stream 5: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(5) stream 5 closing due to java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 13 bytes to the write queue [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3))) Closed stream 5 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) calling subscriber.onError: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] HttpClientImpl(2) body subscriber unregistered: 0 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] HttpResponseInputStream onError called: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) cancelling stream 7: java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(7) stream 7 closing due to java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 13 bytes to the write queue [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 669ms] Http2Connection(SSLTube(SocketTube(3))) Closed stream 7 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] PlainHttpConnection(SocketTube(3)) Closing channel: channel registered with selector, key.interestOps=0, sa.interestOps=0 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SocketTube(3) signal read error: java.io.IOException: connection closed locally [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] Http2Connection(SSLTube(SocketTube(3))) H2 processed(5480) [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] Http2Connection(SSLTube(SocketTube(3))) Stopping scheduler java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection.protocolError(Http2Connection.java:1229) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$ConnectionWindowUpdateSender.windowSizeExceeded(Http2Connection.java:1759) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.WindowUpdateSender.checkWindowSizeExceeded(WindowUpdateSender.java:136) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.WindowUpdateSender.canBufferUnprocessedBytes(WindowUpdateSender.java:124) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Stream.receiveDataFrame(Stream.java:408) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Stream.incoming(Stream.java:557) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection.processFrame(Http2Connection.java:962) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.frame.FramesDecoder.decode(FramesDecoder.java:155) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$FramesController.processReceivedData(Http2Connection.java:240) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection.asyncReceive(Http2Connection.java:770) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$Http2TubeSubscriber.processQueue(Http2Connection.java:1638) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$LockingRestartableTask.run(SequentialScheduler.java:205) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$Http2TubeSubscriber.runOrSchedule(Http2Connection.java:1655) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$Http2TubeSubscriber.onNext(Http2Connection.java:1682) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.Http2Connection$Http2TubeSubscriber.onNext(Http2Connection.java:1616) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SSLTube$DelegateWrapper.onNext(SSLTube.java:210) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SSLTube$SSLSubscriberWrapper.onNext(SSLTube.java:492) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SSLTube$SSLSubscriberWrapper.onNext(SSLTube.java:295) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SubscriberWrapper$DownstreamPusher.run1(SubscriberWrapper.java:316) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SubscriberWrapper$DownstreamPusher.run(SubscriberWrapper.java:259) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$LockingRestartableTask.run(SequentialScheduler.java:205) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:303) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler.runOrSchedule(SequentialScheduler.java:256) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SubscriberWrapper.outgoing(SubscriberWrapper.java:232) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SubscriberWrapper.outgoing(SubscriberWrapper.java:198) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SSLFlowDelegate$Reader.processData(SSLFlowDelegate.java:458) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SSLFlowDelegate$Reader$ReaderDownstreamPusher.run(SSLFlowDelegate.java:282) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$LockingRestartableTask.run(SequentialScheduler.java:205) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$CompleteRestartableTask.run(SequentialScheduler.java:149) [2026-02-05T10:18:14.992Z] at java.net.http/jdk.internal.net.http.common.SequentialScheduler$SchedulableTask.run(SequentialScheduler.java:230) [2026-02-05T10:18:14.992Z] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [2026-02-05T10:18:14.992Z] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [2026-02-05T10:18:14.992Z] at java.base/java.lang.Thread.run(Thread.java:840) [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] Http2Connection(SSLTube(SocketTube(3))) Shutting down h2c (state=shutdown+half-closed-local): java.net.ProtocolException: protocol error: connection window exceeded [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) Unwrapping: 47 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) Decoded 9 bytes out of 47 into buffer of 64 remaining to decode: 0 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) Unwrapped: result: Status = OK HandshakeStatus = NOT_HANDSHAKING [2026-02-05T10:18:14.992Z] bytesConsumed = 47 bytesProduced = 9 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) Unwrapped: consumed: 47 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) sending 9 [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) Adding 9 to outputQ queue [2026-02-05T10:18:14.992Z] DEBUG: [pool-2-thread-1] [5s 670ms] SSL Reader(SocketTube(3)) pushScheduler is stopped! [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 663ms] ExchangeImpl handling HTTP/2 connection creation result [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 670ms] ExchangeImpl creating HTTP/2 streams [2026-02-05T10:18:14.992Z] TestServer(2): Connection writer stopping localhost/127.0.0.1:54580->/127.0.0.1:54583 [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 671ms] Http2Connection(SSLTube(SocketTube(3))):WindowUpdateSender(stream: ?) maxFrameSize=16384, initWindowSize=16384, limit=0 [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 671ms] Exchange checkFor407: all clear [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 671ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(?) sendHeadersOnly() [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3))) connection closed: closing stream %d [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) cancelling stream 9: java.io.IOException: Stream 9 cancelled [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) stream 9 closing due to java.io.IOException: Stream 9 cancelled before subscriber registered [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3))) Channel already closed, no need to reset stream 9 [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3))) Closed stream 9 [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) Stream 9 registered (cancelled: false, registerIfCancelled: false) [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] AsyncSSLConnection(SSLTube(SocketTube(3))) added 69 bytes to the write queue [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] AsyncSSLConnection(SSLTube(SocketTube(3))) signalling the publisher of the write queue [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Exchange checkFor407: all clear [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Exchange sendRequestBody [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) requestSent: streamid=9 but response not received [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) RequestSubscriber: onSubscribe, request 1 [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) RequestSubscriber: onNext(12) [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] Http2Connection(SSLTube(SocketTube(3)))/Stream(9) RequestSubscriber: requestBodyCf is done: cancelling subscription [2026-02-05T10:18:14.992Z] DEBUG: [MainThread] [5s 672ms] HttpClientImpl(2) ClientImpl (async) elapsed 185 millis for POST to https://127.0.0.1:54580/https2/x?reqId=11 [2026-02-05T10:18:14.992Z] TestServer(1): stopping 2 connections [2026-02-05T10:18:14.992Z] TestServer(1): finished [2026-02-05T10:18:14.992Z] TestServer(2): stopping 1 connections [2026-02-05T10:18:14.992Z] TestServer(2): finished [2026-02-05T10:18:14.992Z] java.lang.Exception: failures: 1 [2026-02-05T10:18:14.992Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:104) [2026-02-05T10:18:14.992Z] at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:58) [2026-02-05T10:18:14.992Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:18:14.992Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:18:14.992Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:18:14.992Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:18:14.992Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2026-02-05T10:18:14.992Z] at java.base/java.lang.Thread.run(Thread.java:840) [2026-02-05T10:18:14.992Z] [2026-02-05T10:18:14.992Z] JavaTest Message: Test threw exception: java.lang.Exception: failures: 1 [2026-02-05T10:18:14.992Z] JavaTest Message: shutting down test [2026-02-05T10:18:14.992Z] [2026-02-05T10:18:14.992Z] STATUS:Failed.`main' threw exception: java.lang.Exception: failures: 1 [2026-02-05T10:18:14.992Z] rerun: [2026-02-05T10:18:14.993Z] cd 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\scratch\0' && \ [2026-02-05T10:18:14.993Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\Program Files (x86)\Incredibuild;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Program Files\dotnet;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2026-02-05T10:18:14.993Z] SystemDrive=C: \ [2026-02-05T10:18:14.993Z] SystemRoot='C:\Windows' \ [2026-02-05T10:18:14.993Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:18:14.993Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:18:14.993Z] windir='C:\Windows' \ [2026-02-05T10:18:14.993Z] CLASSPATH='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\testng-7.3.0.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\guice-5.1.0.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jcommander-1.82.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\javatest.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jtreg.jar' \ [2026-02-05T10:18:14.993Z] 'c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image\bin\java' \ [2026-02-05T10:18:14.993Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2026-02-05T10:18:14.993Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2026-02-05T10:18:14.993Z] -Dtest.compiler.opts= \ [2026-02-05T10:18:14.993Z] -Dtest.java.opts= \ [2026-02-05T10:18:14.993Z] -Dtest.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:18:14.993Z] -Dcompile.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:18:14.993Z] -Dtest.timeout.factor=8.0 \ [2026-02-05T10:18:14.993Z] -Dtest.nativepath='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:18:14.993Z] -Dtest.root='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk' \ [2026-02-05T10:18:14.993Z] -Dtest.name=java/net/httpclient/http2/ConnectionFlowControlTest.java \ [2026-02-05T10:18:14.993Z] -Dtest.file='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2\ConnectionFlowControlTest.java' \ [2026-02-05T10:18:14.993Z] -Dtest.src='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2' \ [2026-02-05T10:18:14.993Z] -Dtest.src.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.993Z] -Dtest.classes='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d' \ [2026-02-05T10:18:14.993Z] -Dtest.class.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.993Z] -Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\java\net\httpclient\http2\ConnectionFlowControlTest.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\httpclient\http2;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\1\test\jdk\java\net\httpclient\lib' \ [2026-02-05T10:18:14.993Z] -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' \ [2026-02-05T10:18:14.993Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2026-02-05T10:18:14.993Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2026-02-05T10:18:14.993Z] -ea \ [2026-02-05T10:18:14.993Z] -esa \ [2026-02-05T10:18:14.993Z] -Xmx512m \ [2026-02-05T10:18:14.993Z] -XX:-UseCompressedOops \ [2026-02-05T10:18:14.993Z] '-Djava.library.path=c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:18:14.993Z] -Djdk.internal.httpclient.debug=true \ [2026-02-05T10:18:14.993Z] -Djdk.httpclient.connectionWindowSize=65535 \ [2026-02-05T10:18:14.993Z] -Djdk.httpclient.windowsize=16384 \ [2026-02-05T10:18:14.993Z] com.sun.javatest.regtest.agent.MainWrapper 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\java\net\httpclient\http2\ConnectionFlowControlTest.d\testng.0.jta' java/net/httpclient/http2/ConnectionFlowControlTest.java false ConnectionFlowControlTest [2026-02-05T10:18:14.993Z] [2026-02-05T10:18:14.993Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.Exception: failures: 1 [2026-02-05T10:18:14.993Z] -------------------------------------------------- [2026-02-05T10:42:28.885Z] TEST: java/net/HttpURLConnection/HttpURLConnectionExpect100Test.java [2026-02-05T10:42:28.885Z] TEST JDK: c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image [2026-02-05T10:42:28.885Z] [2026-02-05T10:42:28.885Z] ACTION: build -- Passed. Build successful [2026-02-05T10:42:28.885Z] REASON: Named class compiled on demand [2026-02-05T10:42:28.885Z] TIME: 0.086 seconds [2026-02-05T10:42:28.885Z] messages: [2026-02-05T10:42:28.885Z] command: build HttpURLConnectionExpect100Test [2026-02-05T10:42:28.885Z] reason: Named class compiled on demand [2026-02-05T10:42:28.885Z] started: Thu Feb 05 10:42:14 UTC 2026 [2026-02-05T10:42:28.885Z] Test directory: [2026-02-05T10:42:28.885Z] compile: HttpURLConnectionExpect100Test [2026-02-05T10:42:28.885Z] finished: Thu Feb 05 10:42:14 UTC 2026 [2026-02-05T10:42:28.885Z] elapsed time (seconds): 0.086 [2026-02-05T10:42:28.885Z] [2026-02-05T10:42:28.885Z] ACTION: compile -- Passed. Compilation successful [2026-02-05T10:42:28.885Z] REASON: .class file out of date or does not exist [2026-02-05T10:42:28.885Z] TIME: 0.074 seconds [2026-02-05T10:42:28.885Z] messages: [2026-02-05T10:42:28.885Z] command: compile C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.java [2026-02-05T10:42:28.885Z] reason: .class file out of date or does not exist [2026-02-05T10:42:28.885Z] started: Thu Feb 05 10:42:14 UTC 2026 [2026-02-05T10:42:28.885Z] Mode: agentvm [2026-02-05T10:42:28.885Z] Agent id: 2 [2026-02-05T10:42:28.885Z] finished: Thu Feb 05 10:42:14 UTC 2026 [2026-02-05T10:42:28.885Z] elapsed time (seconds): 0.074 [2026-02-05T10:42:28.885Z] configuration: [2026-02-05T10:42:28.885Z] Boot Layer (javac runtime environment) [2026-02-05T10:42:28.885Z] class path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\javatest.jar [2026-02-05T10:42:28.885Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jtreg.jar [2026-02-05T10:42:28.885Z] patch: java.base C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\patches\java.base [2026-02-05T10:42:28.885Z] [2026-02-05T10:42:28.885Z] javac compilation environment [2026-02-05T10:42:28.885Z] source path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection [2026-02-05T10:42:28.885Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib [2026-02-05T10:42:28.885Z] class path: C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection [2026-02-05T10:42:28.885Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d [2026-02-05T10:42:28.885Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib [2026-02-05T10:42:28.885Z] C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\junit-platform-console-standalone-1.10.2.jar [2026-02-05T10:42:28.885Z] [2026-02-05T10:42:28.885Z] rerun: [2026-02-05T10:42:28.885Z] cd 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\scratch\1' && \ [2026-02-05T10:42:28.885Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\Program Files (x86)\Incredibuild;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Program Files\dotnet;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2026-02-05T10:42:28.885Z] SystemDrive=C: \ [2026-02-05T10:42:28.885Z] SystemRoot='C:\Windows' \ [2026-02-05T10:42:28.885Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:42:28.885Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:42:28.885Z] windir='C:\Windows' \ [2026-02-05T10:42:28.885Z] 'c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image\bin\javac' \ [2026-02-05T10:42:28.885Z] -J-ea \ [2026-02-05T10:42:28.885Z] -J-esa \ [2026-02-05T10:42:28.885Z] -J-Xmx512m \ [2026-02-05T10:42:28.885Z] -J-XX:-UseCompressedOops \ [2026-02-05T10:42:28.885Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2026-02-05T10:42:28.885Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2026-02-05T10:42:28.885Z] -J-Dtest.compiler.opts= \ [2026-02-05T10:42:28.885Z] -J-Dtest.java.opts= \ [2026-02-05T10:42:28.885Z] -J-Dtest.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:42:28.885Z] -J-Dcompile.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:42:28.885Z] -J-Dtest.timeout.factor=8.0 \ [2026-02-05T10:42:28.885Z] -J-Dtest.nativepath='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:42:28.885Z] -J-Dtest.root='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk' \ [2026-02-05T10:42:28.885Z] -J-Dtest.name=java/net/HttpURLConnection/HttpURLConnectionExpect100Test.java \ [2026-02-05T10:42:28.885Z] -J-Dtest.file='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.java' \ [2026-02-05T10:42:28.885Z] -J-Dtest.src='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection' \ [2026-02-05T10:42:28.885Z] -J-Dtest.src.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib' \ [2026-02-05T10:42:28.885Z] -J-Dtest.classes='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d' \ [2026-02-05T10:42:28.886Z] -J-Dtest.class.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib' \ [2026-02-05T10:42:28.886Z] -J-Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib' \ [2026-02-05T10:42:28.886Z] -d 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d' \ [2026-02-05T10:42:28.886Z] -sourcepath 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib' \ [2026-02-05T10:42:28.886Z] -classpath 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\junit-platform-console-standalone-1.10.2.jar' 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.java' [2026-02-05T10:42:28.886Z] [2026-02-05T10:42:28.886Z] ACTION: junit -- Failed. Execution failed: `main' threw exception: java.lang.Exception: JUnit test failure [2026-02-05T10:42:28.886Z] REASON: User specified action: run junit/othervm HttpURLConnectionExpect100Test [2026-02-05T10:42:28.886Z] TIME: 2.45 seconds [2026-02-05T10:42:28.886Z] messages: [2026-02-05T10:42:28.886Z] command: junit HttpURLConnectionExpect100Test [2026-02-05T10:42:28.886Z] reason: User specified action: run junit/othervm HttpURLConnectionExpect100Test [2026-02-05T10:42:28.886Z] started: Thu Feb 05 10:42:14 UTC 2026 [2026-02-05T10:42:28.886Z] Mode: othervm [/othervm specified] [2026-02-05T10:42:28.886Z] finished: Thu Feb 05 10:42:17 UTC 2026 [2026-02-05T10:42:28.886Z] elapsed time (seconds): 2.45 [2026-02-05T10:42:28.886Z] configuration: [2026-02-05T10:42:28.886Z] STDOUT: [2026-02-05T10:42:28.886Z] /127.0.0.1:51303 [2026-02-05T10:42:28.886Z] Getting: field0 [2026-02-05T10:42:28.886Z] Getting: field1 [2026-02-05T10:42:28.886Z] Getting: field2 [2026-02-05T10:42:28.886Z] Getting: field3 [2026-02-05T10:42:28.886Z] Getting: field4 [2026-02-05T10:42:28.886Z] /127.0.0.1:51305 [2026-02-05T10:42:28.886Z] Getting: field0 [2026-02-05T10:42:28.886Z] Getting: field1 [2026-02-05T10:42:28.886Z] Getting: field2 [2026-02-05T10:42:28.886Z] Getting: field3 [2026-02-05T10:42:28.886Z] Getting: field4 [2026-02-05T10:42:28.886Z] STDERR: [2026-02-05T10:42:28.886Z] STARTED HttpURLConnectionExpect100Test::defaultRequestHitCountTest 'defaultRequestHitCountTest()' [2026-02-05T10:42:28.886Z] java.net.SocketException: An established connection was aborted by the software in your host machine [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:77) [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) [2026-02-05T10:42:28.886Z] at java.base/java.lang.reflect.Constructor.newInstanceWithCaller(Constructor.java:500) [2026-02-05T10:42:28.886Z] at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:481) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection$10.run(HttpURLConnection.java:2109) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection$10.run(HttpURLConnection.java:2104) [2026-02-05T10:42:28.886Z] at java.base/java.security.AccessController.doPrivileged(AccessController.java:569) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getChainedException(HttpURLConnection.java:2103) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1661) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1641) [2026-02-05T10:42:28.886Z] at java.base/java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:529) [2026-02-05T10:42:28.886Z] at HttpURLConnectionExpect100Test.defaultRequestHitCountTest(HttpURLConnectionExpect100Test.java:103) [2026-02-05T10:42:28.886Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:42:28.886Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2026-02-05T10:42:28.886Z] at java.base/java.util.ArrayList.forEach(ArrayList.java:1511) [2026-02-05T10:42:28.886Z] Caused by: java.net.SocketException: An established connection was aborted by the software in your host machine [2026-02-05T10:42:28.886Z] at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:330) [2026-02-05T10:42:28.886Z] at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:355) [2026-02-05T10:42:28.886Z] at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:808) [2026-02-05T10:42:28.886Z] at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966) [2026-02-05T10:42:28.886Z] at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:244) [2026-02-05T10:42:28.886Z] at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:284) [2026-02-05T10:42:28.886Z] at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:343) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.http.HttpClient.parseHTTPHeader(HttpClient.java:826) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.http.HttpClient.parseHTTP(HttpClient.java:761) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1740) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1641) [2026-02-05T10:42:28.886Z] at java.base/sun.net.www.protocol.http.HttpURLConnection.getHeaderField(HttpURLConnection.java:3308) [2026-02-05T10:42:28.886Z] at HttpURLConnectionExpect100Test.getHeaderField(HttpURLConnectionExpect100Test.java:126) [2026-02-05T10:42:28.886Z] at HttpURLConnectionExpect100Test.defaultRequestHitCountTest(HttpURLConnectionExpect100Test.java:101) [2026-02-05T10:42:28.886Z] ... 3 more [2026-02-05T10:42:28.886Z] FAILED HttpURLConnectionExpect100Test::defaultRequestHitCountTest 'defaultRequestHitCountTest()' [2026-02-05T10:42:28.886Z] STARTED HttpURLConnectionExpect100Test::expect100ContinueHitCountTest 'expect100ContinueHitCountTest()' [2026-02-05T10:42:28.886Z] SUCCESSFUL HttpURLConnectionExpect100Test::expect100ContinueHitCountTest 'expect100ContinueHitCountTest()' [2026-02-05T10:42:28.886Z] JavaTest Message: JUnit Platform Failure(s): 1 [2026-02-05T10:42:28.886Z] [2026-02-05T10:42:28.886Z] [ JUnit Containers: found 4, started 4, succeeded 4, failed 0, aborted 0, skipped 0] [2026-02-05T10:42:28.886Z] [ JUnit Tests: found 2, started 2, succeeded 1, failed 1, aborted 0, skipped 0] [2026-02-05T10:42:28.886Z] [2026-02-05T10:42:28.886Z] java.lang.Exception: JUnit test failure [2026-02-05T10:42:28.886Z] at com.sun.javatest.regtest.agent.JUnitRunner.runWithJUnitPlatform(JUnitRunner.java:149) [2026-02-05T10:42:28.886Z] at com.sun.javatest.regtest.agent.JUnitRunner.main(JUnitRunner.java:95) [2026-02-05T10:42:28.886Z] at com.sun.javatest.regtest.agent.JUnitRunner.main(JUnitRunner.java:61) [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) [2026-02-05T10:42:28.886Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [2026-02-05T10:42:28.886Z] at java.base/java.lang.reflect.Method.invoke(Method.java:569) [2026-02-05T10:42:28.887Z] at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138) [2026-02-05T10:42:28.887Z] at java.base/java.lang.Thread.run(Thread.java:840) [2026-02-05T10:42:28.887Z] [2026-02-05T10:42:28.887Z] JavaTest Message: Test threw exception: java.lang.Exception: JUnit test failure [2026-02-05T10:42:28.887Z] JavaTest Message: shutting down test [2026-02-05T10:42:28.887Z] [2026-02-05T10:42:28.887Z] STATUS:Failed.`main' threw exception: java.lang.Exception: JUnit test failure [2026-02-05T10:42:28.887Z] rerun: [2026-02-05T10:42:28.887Z] cd 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\scratch\1' && \ [2026-02-05T10:42:28.887Z] PATH='C:\cygwin64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0;C:\Windows\System32\OpenSSH;C:\Users\adoptopenjdk\AppData\Local\Microsoft\WindowsApps;C:\Strawberry\perl\bin;C:\Program Files\Git\cmd;C:\openjdk\jdk-8\bin;C:\apache-ant\apache-ant-1.10.15\bin;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit;C:\Program Files (x86)\Incredibuild;C:\rust\bin;C:\Program Files\IcedTeaWEB\bin;C:\Program Files\dotnet;C:\Users\jenkins\AppData\Local\Microsoft\WindowsApps' \ [2026-02-05T10:42:28.887Z] SystemDrive=C: \ [2026-02-05T10:42:28.887Z] SystemRoot='C:\Windows' \ [2026-02-05T10:42:28.887Z] TEMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:42:28.887Z] TMP='C:\Users\jenkins\AppData\Local\Temp' \ [2026-02-05T10:42:28.887Z] windir='C:\Windows' \ [2026-02-05T10:42:28.887Z] CLASSPATH='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\junit-platform-console-standalone-1.10.2.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\javatest.jar;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jvmtest\openjdk\jtreg\lib\jtreg.jar' \ [2026-02-05T10:42:28.887Z] 'c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image\bin\java' \ [2026-02-05T10:42:28.887Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:-UseCompressedOops' \ [2026-02-05T10:42:28.887Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:-UseCompressedOops' \ [2026-02-05T10:42:28.887Z] -Dtest.compiler.opts= \ [2026-02-05T10:42:28.887Z] -Dtest.java.opts= \ [2026-02-05T10:42:28.887Z] -Dtest.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:42:28.887Z] -Dcompile.jdk='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\j2sdk-image' \ [2026-02-05T10:42:28.887Z] -Dtest.timeout.factor=8.0 \ [2026-02-05T10:42:28.887Z] -Dtest.nativepath='c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:42:28.887Z] -Dtest.root='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk' \ [2026-02-05T10:42:28.887Z] -Dtest.name=java/net/HttpURLConnection/HttpURLConnectionExpect100Test.java \ [2026-02-05T10:42:28.887Z] -Dtest.file='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.java' \ [2026-02-05T10:42:28.887Z] -Dtest.src='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection' \ [2026-02-05T10:42:28.887Z] -Dtest.src.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\lib' \ [2026-02-05T10:42:28.887Z] -Dtest.classes='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d' \ [2026-02-05T10:42:28.887Z] -Dtest.class.path='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib' \ [2026-02-05T10:42:28.887Z] -Dtest.class.path.prefix='C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\openjdk\openjdk-jdk\test\jdk\java\net\HttpURLConnection;C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\classes\0\test\lib' \ [2026-02-05T10:42:28.887Z] -ea \ [2026-02-05T10:42:28.887Z] -esa \ [2026-02-05T10:42:28.887Z] -Xmx512m \ [2026-02-05T10:42:28.887Z] -XX:-UseCompressedOops \ [2026-02-05T10:42:28.887Z] '-Djava.library.path=c:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\jdkbinary\openjdk-test-image\jdk\jtreg\native' \ [2026-02-05T10:42:28.887Z] com.sun.javatest.regtest.agent.MainWrapper 'C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work\java\net\HttpURLConnection\HttpURLConnectionExpect100Test.d\junit.0.jta' java/net/HttpURLConnection/HttpURLConnectionExpect100Test.java HttpURLConnectionExpect100Test [2026-02-05T10:42:28.887Z] [2026-02-05T10:42:28.887Z] TEST RESULT: Failed. Execution failed: `main' threw exception: java.lang.Exception: JUnit test failure [2026-02-05T10:42:28.887Z] -------------------------------------------------- [2026-02-05T10:51:38.348Z] Test results: passed: 826; failed: 2 [2026-02-05T10:51:47.061Z] Report written to C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\report\html\report.html [2026-02-05T10:51:47.061Z] Results written to C:\jenkins\workspace\Test_openjdk17_hs_extended.openjdk_x86-64_windows_rerun\aqa-tests\TKG\output_17702584678675\jdk_net_1\work [2026-02-05T10:51:47.061Z] Error: Some tests failed or other problems occurred. [2026-02-05T10:51:47.061Z] ----------------------------------- [2026-02-05T10:51:47.061Z] jdk_net_1_FAILED [2026-02-05T10:51:47.061Z] ----------------------------------- [2026-02-05T10:51:47.061Z] [2026-02-05T10:51:47.061Z] TEST TEARDOWN: [2026-02-05T10:51:47.061Z] Nothing to be done for teardown. [2026-02-05T10:51:47.061Z] jdk_net_1 Finish Time: Thu Feb 5 10:51:45 2026 Epoch Time (ms): 1770288705877