Test Results Summary Service

No Possible Issues Found via Git Search

jdk_net_0

[2025-06-28T10:23:28.303Z] Running test jdk_net_0 ... [2025-06-28T10:23:28.303Z] =============================================== [2025-06-28T10:23:28.303Z] jdk_net_0 Start Time: Sat Jun 28 10:23:27 2025 Epoch Time (ms): 1751106207029 [2025-06-28T10:23:28.303Z] variation: Mode150 [2025-06-28T10:23:28.303Z] JVM_OPTIONS: -XX:+UseCompressedOops [2025-06-28T10:23:28.303Z] { \ [2025-06-28T10:23:28.303Z] echo ""; echo "TEST SETUP:"; \ [2025-06-28T10:23:28.303Z] echo "Nothing to be done for setup."; \ [2025-06-28T10:23:28.303Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0"; \ [2025-06-28T10:23:28.303Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0"; \ [2025-06-28T10:23:28.303Z] echo ""; echo "TESTING:"; \ [2025-06-28T10:23:28.303Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image/bin/java" -Xmx512m -jar "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/jtreg/lib/jtreg.jar" \ [2025-06-28T10:23:28.303Z] -agentvm -a -ea -esa -v:fail,error,time,nopass -retain:fail,error,*.dmp,javacore.*,heapdump.*,*.trc -ignore:quiet -timeoutFactor:16 -xml:verify -k:'!headful' -concurrency:3 -nativepath:"/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/openjdk-test-image/jdk/jtreg/native" -vmoptions:"-Xmx512m -XX:+UseCompressedOops -Djava.awt.headless=true" \ [2025-06-28T10:23:28.303Z] -w ""/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0"/work" \ [2025-06-28T10:23:28.303Z] -r ""/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0"/report" \ [2025-06-28T10:23:28.303Z] -jdk:"/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image" \ [2025-06-28T10:23:28.303Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk/ProblemList.txt" \ [2025-06-28T10:23:28.303Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../../jvmtest/openjdk/excludes/ProblemList_openjdk21.txt" \ [2025-06-28T10:23:28.303Z] \ [2025-06-28T10:23:28.303Z] -exclude:"/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../openjdk/excludes/vendors/eclipse/ProblemList_openjdk21.txt" \ [2025-06-28T10:23:28.303Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../openjdk/openjdk-jdk/test/jdk:jdk_net"; \ [2025-06-28T10:23:28.303Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "jdk_net_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/..; else echo "-----------------------------------"; echo "jdk_net_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-28T10:23:28.303Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-28T10:23:28.303Z] echo "Nothing to be done for teardown."; \ [2025-06-28T10:23:28.303Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/TestTargetResult"; [2025-06-28T10:23:28.303Z] [2025-06-28T10:23:28.303Z] TEST SETUP: [2025-06-28T10:23:28.303Z] Nothing to be done for setup. [2025-06-28T10:23:28.303Z] [2025-06-28T10:23:28.303Z] TESTING: [2025-06-28T10:23:29.013Z] Directory "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0/work" not found: creating [2025-06-28T10:23:29.014Z] Directory "/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/../TKG/output_17510971267734/jdk_net_0/report" not found: creating [2025-06-28T10:23:54.092Z] XML output with verification to /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work [2025-06-28T11:08:33.909Z] -------------------------------------------------- [2025-06-28T11:08:33.909Z] TEST: java/net/httpclient/HttpSlowServerTest.java [2025-06-28T11:08:33.909Z] TEST JDK: /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image [2025-06-28T11:08:33.909Z] [2025-06-28T11:08:33.909Z] ACTION: build -- Passed. Build successful [2025-06-28T11:08:33.909Z] REASON: User specified action: run build jdk.httpclient.test.lib.common.HttpServerAdapters jdk.test.lib.net.SimpleSSLContext DigestEchoServer HttpSlowServerTest jdk.httpclient.test.lib.common.TestServerConfigurator [2025-06-28T11:08:33.909Z] TIME: 1.546 seconds [2025-06-28T11:08:33.909Z] messages: [2025-06-28T11:08:33.909Z] command: build jdk.httpclient.test.lib.common.HttpServerAdapters jdk.test.lib.net.SimpleSSLContext DigestEchoServer HttpSlowServerTest jdk.httpclient.test.lib.common.TestServerConfigurator [2025-06-28T11:08:33.909Z] reason: User specified action: run build jdk.httpclient.test.lib.common.HttpServerAdapters jdk.test.lib.net.SimpleSSLContext DigestEchoServer HttpSlowServerTest jdk.httpclient.test.lib.common.TestServerConfigurator [2025-06-28T11:08:33.909Z] started: Sat Jun 28 11:07:59 UTC 2025 [2025-06-28T11:08:33.909Z] Test directory: [2025-06-28T11:08:33.909Z] compile: DigestEchoServer, HttpSlowServerTest [2025-06-28T11:08:33.909Z] finished: Sat Jun 28 11:08:01 UTC 2025 [2025-06-28T11:08:33.909Z] elapsed time (seconds): 1.546 [2025-06-28T11:08:33.909Z] [2025-06-28T11:08:33.909Z] ACTION: compile -- Passed. Compilation successful [2025-06-28T11:08:33.909Z] REASON: .class file out of date or does not exist [2025-06-28T11:08:33.909Z] TIME: 1.543 seconds [2025-06-28T11:08:33.909Z] messages: [2025-06-28T11:08:33.909Z] command: compile /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/DigestEchoServer.java /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/HttpSlowServerTest.java [2025-06-28T11:08:33.909Z] reason: .class file out of date or does not exist [2025-06-28T11:08:33.909Z] started: Sat Jun 28 11:07:59 UTC 2025 [2025-06-28T11:08:33.909Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-06-28T11:08:33.909Z] Mode: agentvm [2025-06-28T11:08:33.909Z] Agent id: 1 [2025-06-28T11:08:33.909Z] finished: Sat Jun 28 11:08:01 UTC 2025 [2025-06-28T11:08:33.909Z] elapsed time (seconds): 1.543 [2025-06-28T11:08:33.909Z] configuration: [2025-06-28T11:08:33.909Z] Boot Layer (javac runtime environment) [2025-06-28T11:08:33.909Z] class path: /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar [2025-06-28T11:08:33.909Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar [2025-06-28T11:08:33.909Z] patch: java.base /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/patches/java.base [2025-06-28T11:08:33.909Z] [2025-06-28T11:08:33.909Z] javac compilation environment [2025-06-28T11:08:33.909Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-06-28T11:08:33.909Z] add exports: java.base/sun.net ALL-UNNAMED [2025-06-28T11:08:33.909Z] java.base/sun.net.www ALL-UNNAMED [2025-06-28T11:08:33.909Z] java.base/sun.net.www.http ALL-UNNAMED [2025-06-28T11:08:33.909Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-06-28T11:08:33.909Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-06-28T11:08:33.909Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-06-28T11:08:33.909Z] source path: /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/lib [2025-06-28T11:08:33.910Z] class path: /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib [2025-06-28T11:08:33.910Z] [2025-06-28T11:08:33.910Z] rerun: [2025-06-28T11:08:33.910Z] cd /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/scratch/0 && \ [2025-06-28T11:08:33.910Z] DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1001/bus \ [2025-06-28T11:08:33.910Z] DISPLAY=:0 \ [2025-06-28T11:08:33.910Z] HOME=/home/jenkins \ [2025-06-28T11:08:33.910Z] LANG=en_US.UTF-8 \ [2025-06-28T11:08:33.910Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-06-28T11:08:33.910Z] XDG_RUNTIME_DIR=/run/user/1001 \ [2025-06-28T11:08:33.910Z] XDG_SESSION_CLASS=user \ [2025-06-28T11:08:33.910Z] XDG_SESSION_ID=41739 \ [2025-06-28T11:08:33.910Z] XDG_SESSION_TYPE=tty \ [2025-06-28T11:08:33.910Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image/bin/javac \ [2025-06-28T11:08:33.910Z] -J-ea \ [2025-06-28T11:08:33.910Z] -J-esa \ [2025-06-28T11:08:33.910Z] -J-Xmx512m \ [2025-06-28T11:08:33.910Z] -J-XX:+UseCompressedOops \ [2025-06-28T11:08:33.910Z] -J-Djava.awt.headless=true \ [2025-06-28T11:08:33.910Z] -J-Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops -Djava.awt.headless=true' \ [2025-06-28T11:08:33.910Z] -J-Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops -J-Djava.awt.headless=true' \ [2025-06-28T11:08:33.910Z] -J-Dtest.compiler.opts= \ [2025-06-28T11:08:33.910Z] -J-Dtest.java.opts= \ [2025-06-28T11:08:33.910Z] -J-Dtest.jdk=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-28T11:08:33.910Z] -J-Dcompile.jdk=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-28T11:08:33.910Z] -J-Dtest.timeout.factor=16.0 \ [2025-06-28T11:08:33.910Z] -J-Dtest.nativepath=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-06-28T11:08:33.910Z] -J-Dtest.root=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-06-28T11:08:33.910Z] -J-Dtest.name=java/net/httpclient/HttpSlowServerTest.java \ [2025-06-28T11:08:33.910Z] -J-Dtest.file=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/HttpSlowServerTest.java \ [2025-06-28T11:08:33.910Z] -J-Dtest.src=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient \ [2025-06-28T11:08:33.910Z] -J-Dtest.src.path=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.910Z] -J-Dtest.classes=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d \ [2025-06-28T11:08:33.910Z] -J-Dtest.class.path=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.910Z] -J-Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.910Z] -J-Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-06-28T11:08:33.910Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-06-28T11:08:33.910Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-06-28T11:08:33.910Z] -d /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d \ [2025-06-28T11:08:33.910Z] -sourcepath /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.910Z] -classpath /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/DigestEchoServer.java /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/HttpSlowServerTest.java [2025-06-28T11:08:33.910Z] direct: [2025-06-28T11:08:33.910Z] Note: /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/DigestEchoServer.java uses or overrides a deprecated API. [2025-06-28T11:08:33.910Z] Note: Recompile with -Xlint:deprecation for details. [2025-06-28T11:08:33.910Z] [2025-06-28T11:08:33.910Z] ACTION: build -- Passed. All files up to date [2025-06-28T11:08:33.910Z] REASON: Named class compiled on demand [2025-06-28T11:08:33.910Z] TIME: 0.0 seconds [2025-06-28T11:08:33.910Z] messages: [2025-06-28T11:08:33.910Z] command: build HttpSlowServerTest [2025-06-28T11:08:33.910Z] reason: Named class compiled on demand [2025-06-28T11:08:33.910Z] started: Sat Jun 28 11:08:01 UTC 2025 [2025-06-28T11:08:33.910Z] finished: Sat Jun 28 11:08:01 UTC 2025 [2025-06-28T11:08:33.910Z] elapsed time (seconds): 0.0 [2025-06-28T11:08:33.910Z] [2025-06-28T11:08:33.910Z] ACTION: main -- Failed. Unexpected exit from test [exit code: 134] [2025-06-28T11:08:33.910Z] REASON: User specified action: run main/othervm -Dtest.requiresHost=true -Djdk.httpclient.HttpClient.log=headers -Djdk.internal.httpclient.debug=false HttpSlowServerTest [2025-06-28T11:08:33.910Z] TIME: 19.511 seconds [2025-06-28T11:08:33.910Z] messages: [2025-06-28T11:08:33.910Z] command: main -Dtest.requiresHost=true -Djdk.httpclient.HttpClient.log=headers -Djdk.internal.httpclient.debug=false HttpSlowServerTest [2025-06-28T11:08:33.910Z] reason: User specified action: run main/othervm -Dtest.requiresHost=true -Djdk.httpclient.HttpClient.log=headers -Djdk.internal.httpclient.debug=false HttpSlowServerTest [2025-06-28T11:08:33.910Z] started: Sat Jun 28 11:08:01 UTC 2025 [2025-06-28T11:08:33.910Z] Mode: othervm [/othervm specified] [2025-06-28T11:08:33.910Z] Additional options from @modules: --add-modules java.base,java.net.http,java.logging,jdk.httpserver --add-exports java.base/sun.net.www.http=ALL-UNNAMED --add-exports java.base/sun.net.www=ALL-UNNAMED --add-exports java.base/sun.net=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED [2025-06-28T11:08:33.910Z] finished: Sat Jun 28 11:08:21 UTC 2025 [2025-06-28T11:08:33.910Z] elapsed time (seconds): 19.511 [2025-06-28T11:08:33.910Z] configuration: [2025-06-28T11:08:33.910Z] Boot Layer [2025-06-28T11:08:33.910Z] add modules: java.base java.net.http java.logging jdk.httpserver [2025-06-28T11:08:33.910Z] add exports: java.base/sun.net ALL-UNNAMED [2025-06-28T11:08:33.911Z] java.base/sun.net.www ALL-UNNAMED [2025-06-28T11:08:33.911Z] java.base/sun.net.www.http ALL-UNNAMED [2025-06-28T11:08:33.911Z] java.net.http/jdk.internal.net.http.common ALL-UNNAMED [2025-06-28T11:08:33.911Z] java.net.http/jdk.internal.net.http.frame ALL-UNNAMED [2025-06-28T11:08:33.911Z] java.net.http/jdk.internal.net.http.hpack ALL-UNNAMED [2025-06-28T11:08:33.911Z] [2025-06-28T11:08:33.911Z] STDOUT: [2025-06-28T11:08:33.911Z] Http1TestServer[localhost/127.0.0.1:45751]::addHandler HttpSlowServerTest$HttpTestSlowHandler@1d1fa0ff, /HttpSlowServerTest/http1/ [2025-06-28T11:08:33.911Z] Http1TestServer: start [2025-06-28T11:08:33.911Z] Http1TestServer[localhost/127.0.0.1:42591]::addHandler HttpSlowServerTest$HttpTestSlowHandler@6a679127, /HttpSlowServerTest/https1/ [2025-06-28T11:08:33.911Z] Http1TestServer: start [2025-06-28T11:08:33.911Z] Http2TestServerImpl[localhost/127.0.0.1:33175]::addHandler HttpSlowServerTest$HttpTestSlowHandler@476509a1, /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.911Z] Http2TestServerImpl: start [2025-06-28T11:08:33.911Z] Http2TestServerImpl[localhost/127.0.0.1:57279]::addHandler HttpSlowServerTest$HttpTestSlowHandler@62526274, /HttpSlowServerTest/https2/ [2025-06-28T11:08:33.911Z] Http2TestServerImpl: start [2025-06-28T11:08:33.911Z] Socket bound to: localhost:37309 after 1 attempt(s) [2025-06-28T11:08:33.911Z] [0 s, 93 ms, 902165 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.911Z] Setup: done [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=1 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=2 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=3 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=4 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=5 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=6 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=7 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=8 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=9 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=10 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=11 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=12 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=13 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=14 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=15 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=16 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=17 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=18 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=19 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=20 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=21 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=22 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=23 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=24 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=25 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=26 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=27 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=28 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=29 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=30 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=31 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=32 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=33 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=34 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=35 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=36 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=37 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=38 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=39 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=40 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=41 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=42 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=43 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=44 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=45 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=46 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=47 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=48 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=49 [2025-06-28T11:08:33.911Z] [1 s, 294 ms, 756114 ns] Tunnel: Client accepted [2025-06-28T11:08:33.911Z] [1 s, 300 ms, 394930 ns] Tunnel: Reading request line [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=50 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=51 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=52 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=53 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=54 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=55 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=56 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=57 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=58 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=59 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=60 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=61 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=62 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=63 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=64 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=65 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=66 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=67 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=68 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=69 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=70 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=71 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=72 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=73 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=74 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=75 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=76 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=77 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=78 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=79 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=80 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=81 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=82 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=83 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=84 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=85 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=86 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=87 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=88 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=89 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=90 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=91 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=92 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=93 [2025-06-28T11:08:33.911Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=94 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=95 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=96 [2025-06-28T11:08:33.911Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=97 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=98 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=99 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=100 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=101 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=102 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=103 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=104 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=105 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=106 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=107 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=108 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=109 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=110 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=111 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=112 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=113 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=114 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/proxy/foo?n=115 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/proxy/foo?n=116 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=117 [2025-06-28T11:08:33.912Z] Testing with http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=118 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:42591/HttpSlowServerTest/https1/direct/foo?n=119 [2025-06-28T11:08:33.912Z] Testing with https://127.0.0.1:57279/HttpSlowServerTest/https2/direct/foo?n=120 [2025-06-28T11:08:33.912Z] [3 s, 718 ms, 786605 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.912Z] [3 s, 720 ms, 943995 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [3 s, 724 ms, 40078 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [3 s, 730 ms, 548584 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.912Z] [3 s, 732 ms, 356299 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [3 s, 760 ms, 969727 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [3 s, 775 ms, 250773 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] [4 s, 333 ms, 450291 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 334 ms, 249646 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 334 ms, 875662 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 337 ms, 618735 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 338 ms, 330421 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 340 ms, 325140 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 341 ms, 179497 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.912Z] [4 s, 341 ms, 716844 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 343 ms, 493225 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [4 s, 344 ms, 400582 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] [4 s, 452 ms, 945137 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 453 ms, 966164 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 454 ms, 658516 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 457 ms, 577593 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 458 ms, 337947 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [4 s, 460 ms, 551006 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 461 ms, 253024 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.912Z] [4 s, 462 ms, 48046 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [4 s, 463 ms, 915762 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [4 s, 485 ms, 914347 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] [4 s, 571 ms, 190616 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 571 ms, 993971 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 572 ms, 500317 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 574 ms, 539372 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 575 ms, 225057 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 577 ms, 444782 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 578 ms, 113133 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.912Z] [4 s, 578 ms, 470476 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 579 ms, 873514 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [4 s, 604 ms, 954514 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=57 sending response 57 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=33 sending response 33 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=3 sending response 3 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=9 sending response 9 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=15 sending response 15 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=21 sending response 21 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=51 sending response 51 [2025-06-28T11:08:33.912Z] Server /HttpSlowServerTest/http1/direct/foo?n=27 sending response 27 [2025-06-28T11:08:33.912Z] [4 s, 752 ms, 971452 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 753 ms, 888143 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 754 ms, 685831 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 756 ms, 778553 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 757 ms, 837582 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 759 ms, 791967 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 760 ms, 394650 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.912Z] [4 s, 760 ms, 899996 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 762 ms, 395703 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [4 s, 764 ms, 445424 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] [4 s, 831 ms, 245868 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 832 ms, 273229 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 833 ms, 41582 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 835 ms, 135972 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 835 ms, 868324 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [4 s, 837 ms, 652705 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 838 ms, 315056 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.912Z] [4 s, 838 ms, 848404 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.912Z] [4 s, 840 ms, 300776 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.912Z] Content-Length: 0 [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [2025-06-28T11:08:33.912Z] [4 s, 856 ms, 872216 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.912Z] [4 s, 908 ms, 130914 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.912Z] [4 s, 909 ms, 138274 ns] Tunnel: Client accepted [2025-06-28T11:08:33.912Z] [4 s, 909 ms, 723289 ns] Tunnel: Reading request line [2025-06-28T11:08:33.912Z] [4 s, 912 ms, 201022 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.912Z] [4 s, 913 ms, 593392 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.912Z] [4 s, 915 ms, 117099 ns] Tunnel: Reading header: [2025-06-28T11:08:33.912Z] [4 s, 915 ms, 858452 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.913Z] [4 s, 916 ms, 331465 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [4 s, 932 ms, 418893 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [4 s, 934 ms, 582284 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 52 ms, 990767 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 53 ms, 778455 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 65 ms, 905444 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 68 ms, 171171 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 69 ms, 411871 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 70 ms, 892577 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 71 ms, 455925 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.913Z] [5 s, 71 ms, 768600 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 75 ms, 126690 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 77 ms, 178078 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 112 ms, 949363 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 113 ms, 853387 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 114 ms, 439402 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 116 ms, 548458 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 118 ms, 659848 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 120 ms, 488230 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 146 ms, 512256 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.913Z] [5 s, 147 ms, 113938 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 181 ms, 256847 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 183 ms, 275567 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 224 ms, 997344 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 225 ms, 993370 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 226 ms, 602053 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 228 ms, 597773 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 237 ms, 441341 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 238 ms, 860379 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 239 ms, 506063 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.913Z] [5 s, 239 ms, 925074 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 297 ms, 451938 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 299 ms, 535993 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 358 ms, 786570 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 359 ms, 603925 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 360 ms, 159940 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 362 ms, 105325 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 362 ms, 942347 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 364 ms, 339051 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 365 ms, 188740 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.913Z] [5 s, 365 ms, 760089 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 367 ms, 138792 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 369 ms, 319183 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 452 ms, 993743 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 469 ms, 26169 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 469 ms, 657519 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 471 ms, 460234 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 472 ms, 176587 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 473 ms, 845631 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 474 ms, 384312 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.913Z] [5 s, 474 ms, 748988 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 476 ms, 653706 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 478 ms, 616091 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 508 ms, 140877 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 509 ms, 90235 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 509 ms, 609582 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 511 ms, 413630 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 512 ms, 128649 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 533 ms, 603221 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 534 ms, 699917 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.913Z] [5 s, 535 ms, 48259 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 536 ms, 406295 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 569 ms, 542510 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 649 ms, 38625 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 649 ms, 784978 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 650 ms, 287325 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 652 ms, 119707 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 661 ms, 648294 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 664 ms, 416368 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 665 ms, 537397 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.913Z] [5 s, 691 ms, 341084 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 715 ms, 285721 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 716 ms, 767760 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] [5 s, 832 ms, 974185 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 833 ms, 707205 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 834 ms, 228552 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 835 ms, 827595 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 836 ms, 479279 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 838 ms, 310661 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 839 ms, 54014 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.913Z] [5 s, 839 ms, 817701 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [5 s, 841 ms, 928090 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 842 ms, 910450 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] resp:15: wrote 11 bytes [2025-06-28T11:08:33.913Z] [5 s, 914 ms, 867031 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [5 s, 915 ms, 669386 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [5 s, 916 ms, 253401 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [5 s, 917 ms, 774442 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.913Z] [5 s, 918 ms, 518128 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 919 ms, 736827 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [5 s, 920 ms, 298842 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.913Z] [5 s, 920 ms, 961193 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.913Z] [5 s, 922 ms, 383564 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.913Z] Content-Length: 0 [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [2025-06-28T11:08:33.913Z] [5 s, 923 ms, 312922 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.913Z] resp:57: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:51: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:27: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:21: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:9: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:33: wrote 11 bytes [2025-06-28T11:08:33.913Z] resp:3: wrote 11 bytes [2025-06-28T11:08:33.913Z] [6 s, 28 ms, 974067 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.913Z] [6 s, 29 ms, 648085 ns] Tunnel: Client accepted [2025-06-28T11:08:33.913Z] [6 s, 30 ms, 111430 ns] Tunnel: Reading request line [2025-06-28T11:08:33.913Z] [6 s, 31 ms, 778475 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.913Z] [6 s, 32 ms, 503161 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.913Z] [6 s, 33 ms, 733860 ns] Tunnel: Reading header: [2025-06-28T11:08:33.913Z] [6 s, 34 ms, 312875 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.914Z] [6 s, 34 ms, 693552 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.914Z] [6 s, 36 ms, 46921 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.914Z] Content-Length: 0 [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [6 s, 36 ms, 989613 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.914Z] [6 s, 84 ms, 990557 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.914Z] [6 s, 85 ms, 829246 ns] Tunnel: Client accepted [2025-06-28T11:08:33.914Z] [6 s, 86 ms, 505931 ns] Tunnel: Reading request line [2025-06-28T11:08:33.914Z] [6 s, 89 ms, 134001 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.914Z] [6 s, 90 ms, 27358 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.914Z] [6 s, 142 ms, 540088 ns] Tunnel: Reading header: [2025-06-28T11:08:33.914Z] [6 s, 143 ms, 195772 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.914Z] [6 s, 143 ms, 582782 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.914Z] [6 s, 145 ms, 194159 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.914Z] Content-Length: 0 [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [6 s, 156 ms, 902803 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.914Z] resp:15: wrote 14 bytes [2025-06-28T11:08:33.914Z] [6 s, 228 ms, 947054 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.914Z] [6 s, 229 ms, 809076 ns] Tunnel: Client accepted [2025-06-28T11:08:33.914Z] resp:57: wrote 14 bytes [2025-06-28T11:08:33.914Z] [6 s, 230 ms, 630098 ns] Tunnel: Reading request line [2025-06-28T11:08:33.914Z] [6 s, 232 ms, 140805 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.914Z] [6 s, 232 ms, 769155 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.914Z] [6 s, 233 ms, 935186 ns] Tunnel: Reading header: [2025-06-28T11:08:33.914Z] [6 s, 234 ms, 407199 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.914Z] [6 s, 234 ms, 694540 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.914Z] resp:51: wrote 14 bytes [2025-06-28T11:08:33.914Z] [6 s, 235 ms, 947573 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.914Z] Content-Length: 0 [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] resp:27: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 14 bytes [2025-06-28T11:08:33.914Z] [6 s, 284 ms, 357861 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.914Z] resp:3: wrote 14 bytes [2025-06-28T11:08:33.914Z] [6 s, 409 ms, 23178 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.914Z] [6 s, 409 ms, 674528 ns] Tunnel: Client accepted [2025-06-28T11:08:33.914Z] [6 s, 410 ms, 92206 ns] Tunnel: Reading request line [2025-06-28T11:08:33.914Z] [6 s, 411 ms, 526244 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.914Z] [6 s, 412 ms, 88926 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.914Z] [6 s, 413 ms, 205956 ns] Tunnel: Reading header: [2025-06-28T11:08:33.914Z] [6 s, 413 ms, 729636 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.914Z] [6 s, 414 ms, 118313 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.914Z] [6 s, 415 ms, 494683 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.914Z] Content-Length: 0 [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [6 s, 417 ms, 488070 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.914Z] resp:15: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:3: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:27: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:15: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:3: wrote 33 bytes [2025-06-28T11:08:33.914Z] resp:27: wrote 33 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@52c3cffb [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@5f571694 [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@72c6b63f [2025-06-28T11:08:33.914Z] resp:15: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 12 bytes [2025-06-28T11:08:33.914Z] [7 s, 173 ms, 18837 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.914Z] [7 s, 173 ms, 702522 ns] Tunnel: Client accepted [2025-06-28T11:08:33.914Z] [7 s, 174 ms, 126866 ns] Tunnel: Reading request line [2025-06-28T11:08:33.914Z] [7 s, 199 ms, 427540 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.914Z] resp:3: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:27: wrote 12 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 12 bytes [2025-06-28T11:08:33.914Z] [7 s, 200 ms, 183893 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.914Z] [7 s, 245 ms, 68087 ns] Tunnel: Reading header: [2025-06-28T11:08:33.914Z] [7 s, 245 ms, 584768 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=16 sending response 16 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=40 sending response 40 [2025-06-28T11:08:33.914Z] [7 s, 245 ms, 889442 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.914Z] [7 s, 322 ms, 333810 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.914Z] Content-Length: 0 [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [2025-06-28T11:08:33.914Z] [7 s, 362 ms, 198203 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.914Z] resp:15: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 2 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@35121ce7 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=34 sending response 34 [2025-06-28T11:08:33.914Z] resp:16: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:40: wrote 11 bytes [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=22 sending response 22 [2025-06-28T11:08:33.914Z] resp:22: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:34: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:3: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:27: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 2 bytes [2025-06-28T11:08:33.914Z] resp:15: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:16: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:40: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:22: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:34: wrote 14 bytes [2025-06-28T11:08:33.914Z] resp:3: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:33: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:27: wrote 32 bytes [2025-06-28T11:08:33.914Z] resp:15: wrote 62 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@4557276e [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=46 sending response 46 [2025-06-28T11:08:33.914Z] resp:46: wrote 11 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@41fd22e1 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=10 sending response 10 [2025-06-28T11:08:33.914Z] resp:10: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:57: wrote 62 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@42dd2d4f [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=76 sending response 76 [2025-06-28T11:08:33.914Z] resp:76: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:51: wrote 62 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@4e702707 [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@69213545 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=58 sending response 58 [2025-06-28T11:08:33.914Z] resp:58: wrote 11 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@1f42ede2 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=28 sending response 28 [2025-06-28T11:08:33.914Z] resp:28: wrote 11 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@6f38220 [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=52 sending response 52 [2025-06-28T11:08:33.914Z] resp:52: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:21: wrote 62 bytes [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=64 sending response 64 [2025-06-28T11:08:33.914Z] resp:16: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:64: wrote 11 bytes [2025-06-28T11:08:33.914Z] resp:40: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:22: wrote 50 bytes [2025-06-28T11:08:33.914Z] resp:9: wrote 62 bytes [2025-06-28T11:08:33.914Z] resp:34: wrote 50 bytes [2025-06-28T11:08:33.914Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@751852bd [2025-06-28T11:08:33.914Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=94 sending response 94 [2025-06-28T11:08:33.915Z] resp:94: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:3: wrote 62 bytes [2025-06-28T11:08:33.915Z] resp:33: wrote 62 bytes [2025-06-28T11:08:33.915Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@336e3cae [2025-06-28T11:08:33.915Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=4 sending response 4 [2025-06-28T11:08:33.915Z] resp:4: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:27: wrote 62 bytes [2025-06-28T11:08:33.915Z] resp:15: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:46: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:10: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:57: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:76: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:51: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:58: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:28: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:52: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:21: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:16: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:64: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:40: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:22: wrote 33 bytes [2025-06-28T11:08:33.915Z] [8 s, 361 ms, 410121 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.915Z] [8 s, 362 ms, 63471 ns] Tunnel: Client accepted [2025-06-28T11:08:33.915Z] [8 s, 362 ms, 498483 ns] Tunnel: Reading request line [2025-06-28T11:08:33.915Z] [8 s, 363 ms, 695181 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.915Z] resp:9: wrote 39 bytes [2025-06-28T11:08:33.915Z] [8 s, 364 ms, 286864 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.915Z] [8 s, 366 ms, 44910 ns] Tunnel: Reading header: [2025-06-28T11:08:33.915Z] [8 s, 366 ms, 506923 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.915Z] [8 s, 366 ms, 785597 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.915Z] [8 s, 367 ms, 955628 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.915Z] Content-Length: 0 [2025-06-28T11:08:33.915Z] [2025-06-28T11:08:33.915Z] [2025-06-28T11:08:33.915Z] [8 s, 368 ms, 674647 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.915Z] resp:34: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:94: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:3: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:33: wrote 39 bytes [2025-06-28T11:08:33.915Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@158fefdd [2025-06-28T11:08:33.915Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=70 sending response 70 [2025-06-28T11:08:33.915Z] resp:70: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:4: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:27: wrote 39 bytes [2025-06-28T11:08:33.915Z] resp:15: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:46: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:10: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:57: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:76: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:51: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:58: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:28: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:52: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:21: wrote 47 bytes [2025-06-28T11:08:33.915Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@2010b1c5 [2025-06-28T11:08:33.915Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=100 sending response 100 [2025-06-28T11:08:33.915Z] resp:100: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:16: wrote 12 bytes [2025-06-28T11:08:33.915Z] resp:64: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:40: wrote 12 bytes [2025-06-28T11:08:33.915Z] resp:22: wrote 12 bytes [2025-06-28T11:08:33.915Z] resp:9: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:34: wrote 12 bytes [2025-06-28T11:08:33.915Z] resp:94: wrote 50 bytes [2025-06-28T11:08:33.915Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@7ad5cce6 [2025-06-28T11:08:33.915Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=106 sending response 106 [2025-06-28T11:08:33.915Z] resp:106: wrote 11 bytes [2025-06-28T11:08:33.915Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@3be315fc [2025-06-28T11:08:33.915Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=118 sending response 118 [2025-06-28T11:08:33.915Z] resp:118: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:33: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:3: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:70: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:4: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:27: wrote 47 bytes [2025-06-28T11:08:33.915Z] resp:15: done [2025-06-28T11:08:33.915Z] resp:46: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:10: wrote 33 bytes [2025-06-28T11:08:33.915Z] Server /HttpSlowServerTest/http1/direct/foo?n=39 sending response 39 [2025-06-28T11:08:33.915Z] resp:39: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:57: done [2025-06-28T11:08:33.915Z] Server /HttpSlowServerTest/http1/direct/foo?n=45 sending response 45 [2025-06-28T11:08:33.915Z] resp:76: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:51: done [2025-06-28T11:08:33.915Z] resp:58: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:28: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:52: wrote 33 bytes [2025-06-28T11:08:33.915Z] Server /HttpSlowServerTest/http1/direct/foo?n=63 sending response 63 [2025-06-28T11:08:33.915Z] resp:21: done [2025-06-28T11:08:33.915Z] resp:63: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:45: wrote 11 bytes [2025-06-28T11:08:33.915Z] resp:100: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:16: wrote 2 bytes [2025-06-28T11:08:33.915Z] resp:64: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:40: wrote 2 bytes [2025-06-28T11:08:33.915Z] resp:22: wrote 2 bytes [2025-06-28T11:08:33.915Z] resp:9: done [2025-06-28T11:08:33.915Z] resp:34: wrote 2 bytes [2025-06-28T11:08:33.915Z] resp:94: wrote 33 bytes [2025-06-28T11:08:33.915Z] resp:106: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:118: wrote 14 bytes [2025-06-28T11:08:33.915Z] resp:33: done [2025-06-28T11:08:33.915Z] resp:3: done [2025-06-28T11:08:33.915Z] resp:70: wrote 50 bytes [2025-06-28T11:08:33.915Z] resp:4: wrote 33 bytes [2025-06-28T11:08:33.915Z] [9 s, 165 ms, 503180 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.915Z] [9 s, 166 ms, 383870 ns] Tunnel: Client accepted [2025-06-28T11:08:33.915Z] [9 s, 166 ms, 848549 ns] Tunnel: Reading request line [2025-06-28T11:08:33.915Z] [9 s, 167 ms, 956245 ns] Tunnel: Request line: CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.915Z] [9 s, 168 ms, 568261 ns] Tunnel: Reading header: Host: 127.0.0.1:42591 [2025-06-28T11:08:33.917Z] [9 s, 169 ms, 608289 ns] Tunnel: Reading header: [2025-06-28T11:08:33.917Z] [9 s, 170 ms, 262306 ns] Tunnel: Host header verified [127.0.0.1:42591] [2025-06-28T11:08:33.917Z] [9 s, 170 ms, 633983 ns] Tunnel connecting to target server at /127.0.0.1:42591 [2025-06-28T11:08:33.917Z] [9 s, 171 ms, 979019 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.917Z] Content-Length: 0 [2025-06-28T11:08:33.917Z] [2025-06-28T11:08:33.917Z] [2025-06-28T11:08:33.917Z] [9 s, 173 ms, 506726 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.917Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@6e1e2bc [2025-06-28T11:08:33.917Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=88 sending response 88 [2025-06-28T11:08:33.917Z] resp:88: wrote 11 bytes [2025-06-28T11:08:33.917Z] resp:27: done [2025-06-28T11:08:33.917Z] resp:46: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:10: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:39: wrote 14 bytes [2025-06-28T11:08:33.917Z] resp:76: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:58: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:28: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:52: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:63: wrote 14 bytes [2025-06-28T11:08:33.917Z] resp:45: wrote 14 bytes [2025-06-28T11:08:33.917Z] resp:100: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:16: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:64: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:40: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:22: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:34: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:94: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:106: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:118: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:70: wrote 33 bytes [2025-06-28T11:08:33.917Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@535348bf [2025-06-28T11:08:33.917Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=112 sending response 112 [2025-06-28T11:08:33.917Z] resp:112: wrote 11 bytes [2025-06-28T11:08:33.917Z] resp:4: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:88: wrote 14 bytes [2025-06-28T11:08:33.917Z] resp:46: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:10: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:76: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:39: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:58: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:52: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:28: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:16: wrote 62 bytes [2025-06-28T11:08:33.917Z] resp:45: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:100: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:64: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:40: wrote 62 bytes [2025-06-28T11:08:33.917Z] resp:22: wrote 62 bytes [2025-06-28T11:08:33.917Z] resp:34: wrote 62 bytes [2025-06-28T11:08:33.917Z] resp:63: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:94: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:106: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:118: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:70: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:112: wrote 14 bytes [2025-06-28T11:08:33.917Z] resp:4: wrote 2 bytes [2025-06-28T11:08:33.917Z] resp:88: wrote 50 bytes [2025-06-28T11:08:33.917Z] resp:46: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:10: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:76: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:39: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:28: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:58: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:16: wrote 39 bytes [2025-06-28T11:08:33.917Z] resp:45: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:100: wrote 12 bytes [2025-06-28T11:08:33.917Z] resp:64: wrote 32 bytes [2025-06-28T11:08:33.917Z] resp:40: wrote 39 bytes [2025-06-28T11:08:33.917Z] resp:22: wrote 39 bytes [2025-06-28T11:08:33.917Z] resp:34: wrote 39 bytes [2025-06-28T11:08:33.917Z] resp:63: wrote 33 bytes [2025-06-28T11:08:33.917Z] resp:52: wrote 32 bytes [2025-06-28T11:08:33.918Z] Http2TestContext::handle H2ExchangeImpl: jdk.httpclient.test.lib.http2.Http2TestExchangeImpl@30f7b12e [2025-06-28T11:08:33.918Z] Server http://127.0.0.1:33175/HttpSlowServerTest/http2/direct/foo?n=82 sending response 82 [2025-06-28T11:08:33.918Z] resp:82: wrote 11 bytes [2025-06-28T11:08:33.918Z] resp:94: wrote 32 bytes [2025-06-28T11:08:33.918Z] resp:106: wrote 12 bytes [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=51 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=51) 200 [still pending: 117] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=21 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=21) 200 [still pending: 116] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=9 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=9) 200 [still pending: 115] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=33 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=33) 200 [still pending: 114] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=3 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=3) 200 [still pending: 113] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=27 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=27) 200 [still pending: 112] [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=15 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=15) 200 [still pending: 118] [2025-06-28T11:08:33.918Z] resp:118: wrote 12 bytes [2025-06-28T11:08:33.918Z] http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=57 GET -> (GET http://127.0.0.1:45751/HttpSlowServerTest/http1/direct/foo?n=57) 200 [still pending: 119] [2025-06-28T11:08:33.918Z] resp:70: wrote 2 bytes [2025-06-28T11:08:33.918Z] resp:112: wrote 50 bytes [2025-06-28T11:08:33.918Z] [10 s, 72 ms, 977990 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.918Z] [10 s, 73 ms, 739010 ns] Tunnel: Client accepted [2025-06-28T11:08:33.918Z] [10 s, 74 ms, 259690 ns] Tunnel: Reading request line [2025-06-28T11:08:33.918Z] [10 s, 75 ms, 397721 ns] Tunnel: Request line: CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.918Z] [10 s, 76 ms, 22071 ns] Tunnel: Reading header: Host: 127.0.0.1:57279 [2025-06-28T11:08:33.918Z] [10 s, 77 ms, 113433 ns] Tunnel: Reading header: [2025-06-28T11:08:33.918Z] [10 s, 77 ms, 501110 ns] Tunnel: Host header verified [127.0.0.1:57279] [2025-06-28T11:08:33.918Z] [10 s, 77 ms, 950789 ns] Tunnel connecting to target server at /127.0.0.1:57279 [2025-06-28T11:08:33.918Z] resp:4: wrote 32 bytes [2025-06-28T11:08:33.918Z] [10 s, 80 ms, 64511 ns] Tunnel: Sending HTTP/1.1 200 OK [2025-06-28T11:08:33.918Z] Content-Length: 0 [2025-06-28T11:08:33.918Z] [2025-06-28T11:08:33.918Z] [2025-06-28T11:08:33.918Z] [10 s, 80 ms, 856199 ns] Tunnel: Starting tunnel pipes [2025-06-28T11:08:33.918Z] resp:88: wrote 33 bytes [2025-06-28T11:08:33.918Z] resp:10: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:46: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:76: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:28: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:16: wrote 47 bytes [2025-06-28T11:08:33.918Z] resp:58: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:39: wrote 12 bytes [2025-06-28T11:08:33.918Z] resp:45: wrote 12 bytes [2025-06-28T11:08:33.918Z] resp:100: wrote 2 bytes [2025-06-28T11:08:33.918Z] resp:64: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:40: wrote 47 bytes [2025-06-28T11:08:33.918Z] resp:22: wrote 47 bytes [2025-06-28T11:08:33.918Z] resp:34: wrote 47 bytes [2025-06-28T11:08:33.918Z] resp:63: wrote 12 bytes [2025-06-28T11:08:33.918Z] resp:52: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:82: wrote 14 bytes [2025-06-28T11:08:33.918Z] resp:94: wrote 62 bytes [2025-06-28T11:08:33.918Z] resp:106: wrote 2 bytes [2025-06-28T11:08:33.918Z] resp:118: wrote 2 bytes [2025-06-28T11:08:33.918Z] [10 s, 293 ms, 29844 ns] Tunnel: Waiting for client [2025-06-28T11:08:33.918Z] [10 s, 293 ms, 730529 ns] Tunnel: Client accepted [2025-06-28T11:08:33.918Z] [10 s, 294 ms, 130873 ns] Tunnel: Reading request line [2025-06-28T11:08:33.918Z] STDERR: [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] WARNING: HttpsProxyTunnel is an experimental test class [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47522, Session(1751108889205|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47516, Session(1751108887833|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43882,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47542, Session(1751108889264|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43886,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43894,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47546, Session(1751108889337|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43898,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47566, Session(1751108889382|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43930,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.918Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47572, Session(1751108889436|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.918Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(1): accepting connections [2025-06-28T11:08:33.918Z] TestServer(1): connection accepted [2025-06-28T11:08:33.918Z] TestServer(1): creating connection [2025-06-28T11:08:33.918Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43932,localport=33175] [2025-06-28T11:08:33.918Z] TestServer(1): starting connection [2025-06-28T11:08:33.918Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.918Z] TestServer(2): accepting connections [2025-06-28T11:08:33.918Z] TestServer(2): connection accepted [2025-06-28T11:08:33.918Z] TestServer(2): creating connection [2025-06-28T11:08:33.919Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47598, Session(1751108889455|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.919Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(1): accepting connections [2025-06-28T11:08:33.919Z] TestServer(1): connection accepted [2025-06-28T11:08:33.919Z] TestServer(1): creating connection [2025-06-28T11:08:33.919Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43936,localport=33175] [2025-06-28T11:08:33.919Z] TestServer(1): starting connection [2025-06-28T11:08:33.919Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(2): accepting connections [2025-06-28T11:08:33.919Z] TestServer(2): connection accepted [2025-06-28T11:08:33.919Z] TestServer(2): creating connection [2025-06-28T11:08:33.919Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47612, Session(1751108889481|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.919Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(1): accepting connections [2025-06-28T11:08:33.919Z] TestServer(1): connection accepted [2025-06-28T11:08:33.919Z] TestServer(1): creating connection [2025-06-28T11:08:33.919Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43942,localport=33175] [2025-06-28T11:08:33.919Z] TestServer(1): starting connection [2025-06-28T11:08:33.919Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(2): accepting connections [2025-06-28T11:08:33.919Z] TestServer(2): connection accepted [2025-06-28T11:08:33.919Z] TestServer(2): creating connection [2025-06-28T11:08:33.919Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47614, Session(1751108889507|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.919Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(1): accepting connections [2025-06-28T11:08:33.919Z] TestServer(1): creating connection [2025-06-28T11:08:33.919Z] TestServer(1): connection accepted [2025-06-28T11:08:33.919Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43966,localport=33175] [2025-06-28T11:08:33.919Z] TestServer(1): starting connection [2025-06-28T11:08:33.919Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(2): accepting connections [2025-06-28T11:08:33.919Z] TestServer(2): creating connection [2025-06-28T11:08:33.919Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47630, Session(1751108889530|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.919Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(1): accepting connections [2025-06-28T11:08:33.919Z] TestServer(1): creating connection [2025-06-28T11:08:33.919Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43978,localport=33175] [2025-06-28T11:08:33.919Z] TestServer(1): starting connection [2025-06-28T11:08:33.919Z] TestServer(1): connection accepted [2025-06-28T11:08:33.919Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(1): accepting connections [2025-06-28T11:08:33.919Z] TestServer(1): creating connection [2025-06-28T11:08:33.919Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=43988,localport=33175] [2025-06-28T11:08:33.919Z] TestServer(1): starting connection [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] GET /HttpSlowServerTest/http1/direct/foo?n=15 HTTP/1.1 [2025-06-28T11:08:33.919Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.919Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.919Z] Upgrade: h2c [2025-06-28T11:08:33.919Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] GET /HttpSlowServerTest/http2/direct/foo?n=4 HTTP/1.1 [2025-06-28T11:08:33.919Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.919Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.919Z] Upgrade: h2c [2025-06-28T11:08:33.919Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] GET /HttpSlowServerTest/http1/direct/foo?n=9 HTTP/1.1 [2025-06-28T11:08:33.919Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.919Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.919Z] Upgrade: h2c [2025-06-28T11:08:33.919Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] TestServer(2): connection accepted [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] GET /HttpSlowServerTest/http2/direct/foo?n=16 HTTP/1.1 [2025-06-28T11:08:33.919Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.919Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.919Z] Upgrade: h2c [2025-06-28T11:08:33.919Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] Jun 28, 2025 11:08:09 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.919Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.919Z] GET /HttpSlowServerTest/http1/direct/foo?n=3 HTTP/1.1 [2025-06-28T11:08:33.919Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.919Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.919Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.919Z] Upgrade: h2c [2025-06-28T11:08:33.919Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.919Z] [2025-06-28T11:08:33.919Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.919Z] TestServer(2): accepting connections [2025-06-28T11:08:33.919Z] TestServer(2): creating connection [2025-06-28T11:08:33.919Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47640, Session(1751108889562|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.919Z] TestServer(2): connection accepted [2025-06-28T11:08:33.919Z] TestServer(1): connection accepted [2025-06-28T11:08:33.919Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.920Z] TestServer(2): accepting connections [2025-06-28T11:08:33.920Z] TestServer(2): creating connection [2025-06-28T11:08:33.920Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47652, Session(1751108889985|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=10 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=22 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.920Z] TestServer(1): accepting connections [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(1): creating connection [2025-06-28T11:08:33.920Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44006,localport=33175] [2025-06-28T11:08:33.920Z] TestServer(1): starting connection [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http1/direct/foo?n=27 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http1/direct/foo?n=21 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(1): connection accepted [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.920Z] TestServer(1): accepting connections [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=34 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(1): creating connection [2025-06-28T11:08:33.920Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44014,localport=33175] [2025-06-28T11:08:33.920Z] TestServer(1): starting connection [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=40 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http1/direct/foo?n=39 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http1/direct/foo?n=33 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http1/direct/foo?n=45 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=28 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] GET /HttpSlowServerTest/http2/direct/foo?n=46 HTTP/1.1 [2025-06-28T11:08:33.920Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.920Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.920Z] Upgrade: h2c [2025-06-28T11:08:33.920Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(2): connection accepted [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.920Z] TestServer(2): accepting connections [2025-06-28T11:08:33.920Z] TestServer(2): creating connection [2025-06-28T11:08:33.920Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47670, Session(1751108891284|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.920Z] [2025-06-28T11:08:33.920Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.920Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.920Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.920Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http2/direct/foo?n=52 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44028,localport=33175] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http1/direct/foo?n=51 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http1/direct/foo?n=57 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http2/direct/foo?n=58 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(2): accepting connections [2025-06-28T11:08:33.921Z] TestServer(2): creating connection [2025-06-28T11:08:33.921Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47680, Session(1751108891419|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44040,localport=33175] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http1/direct/foo?n=63 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http2/direct/foo?n=64 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(2): accepting connections [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(2): creating connection [2025-06-28T11:08:33.921Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47694, Session(1751108891538|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44058,localport=33175] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(2): accepting connections [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(2): creating connection [2025-06-28T11:08:33.921Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47704, Session(1751108891601|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44060,localport=33175] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(2): accepting connections [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(2): creating connection [2025-06-28T11:08:33.921Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47714, Session(1751108891629|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44076,localport=33175] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(1): accepting connections [2025-06-28T11:08:33.921Z] TestServer(1): connection accepted [2025-06-28T11:08:33.921Z] TestServer(1): creating connection [2025-06-28T11:08:33.921Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44090,localport=33175] [2025-06-28T11:08:33.921Z] TestServer(1): starting connection [2025-06-28T11:08:33.921Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.921Z] TestServer(2): accepting connections [2025-06-28T11:08:33.921Z] TestServer(2): connection accepted [2025-06-28T11:08:33.921Z] TestServer(2): creating connection [2025-06-28T11:08:33.921Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47728, Session(1751108891685|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http1/direct/foo?n=69 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] GET /HttpSlowServerTest/http2/direct/foo?n=70 HTTP/1.1 [2025-06-28T11:08:33.921Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.921Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.921Z] Upgrade: h2c [2025-06-28T11:08:33.921Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.921Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.921Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.921Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.921Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.921Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http1/direct/foo?n=75 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.922Z] TestServer(2): accepting connections [2025-06-28T11:08:33.922Z] TestServer(2): connection accepted [2025-06-28T11:08:33.922Z] TestServer(2): creating connection [2025-06-28T11:08:33.922Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47738, Session(1751108891724|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=76 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(1): stopping is: false [2025-06-28T11:08:33.922Z] TestServer(1): accepting connections [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=82 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http1/direct/foo?n=81 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:11 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http1/direct/foo?n=87 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.922Z] TestServer(2): accepting connections [2025-06-28T11:08:33.922Z] TestServer(2): creating connection [2025-06-28T11:08:33.922Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47752, Session(1751108891835|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.922Z] TestServer(1): creating connection [2025-06-28T11:08:33.922Z] TestServer(1): New connection from Socket[addr=/127.0.0.1,port=44102,localport=33175] [2025-06-28T11:08:33.922Z] TestServer(1): starting connection [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=88 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http1/direct/foo?n=93 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(2): connection accepted [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.922Z] TestServer(2): accepting connections [2025-06-28T11:08:33.922Z] TestServer(2): creating connection [2025-06-28T11:08:33.922Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47758, Session(1751108892014|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=94 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=100 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.922Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.922Z] content-length: 0 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http2/direct/foo?n=112 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] CONNECT 127.0.0.1:42591 HTTP/1.1 [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:42591 [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] TestServer(2): connection accepted [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.922Z] GET /HttpSlowServerTest/http1/direct/foo?n=111 HTTP/1.1 [2025-06-28T11:08:33.922Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.922Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.922Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.922Z] Upgrade: h2c [2025-06-28T11:08:33.922Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.922Z] [2025-06-28T11:08:33.922Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.922Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] GET /HttpSlowServerTest/http1/direct/foo?n=105 HTTP/1.1 [2025-06-28T11:08:33.923Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.923Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.923Z] Upgrade: h2c [2025-06-28T11:08:33.923Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] GET /HttpSlowServerTest/http2/direct/foo?n=106 HTTP/1.1 [2025-06-28T11:08:33.923Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.923Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.923Z] Upgrade: h2c [2025-06-28T11:08:33.923Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] GET /HttpSlowServerTest/http1/direct/foo?n=117 HTTP/1.1 [2025-06-28T11:08:33.923Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.923Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.923Z] Upgrade: h2c [2025-06-28T11:08:33.923Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] CONNECT 127.0.0.1:57279 HTTP/1.1 [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:57279 [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] GET /HttpSlowServerTest/http1/direct/foo?n=99 HTTP/1.1 [2025-06-28T11:08:33.923Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:45751 [2025-06-28T11:08:33.923Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.923Z] Upgrade: h2c [2025-06-28T11:08:33.923Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.923Z] TestServer(2): accepting connections [2025-06-28T11:08:33.923Z] TestServer(2): creating connection [2025-06-28T11:08:33.923Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47762, Session(1751108892260|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Request logHeaders [2025-06-28T11:08:33.923Z] INFO: HEADERS: REQUEST HEADERS: [2025-06-28T11:08:33.923Z] GET /HttpSlowServerTest/http2/direct/foo?n=118 HTTP/1.1 [2025-06-28T11:08:33.923Z] Connection: Upgrade, HTTP2-Settings [2025-06-28T11:08:33.923Z] Host: 127.0.0.1:33175 [2025-06-28T11:08:33.923Z] HTTP2-Settings: AAEAAEAAAAIAAAAAAAMAAAAAAAQBAAAAAAUAAEAAAAYABgAA [2025-06-28T11:08:33.923Z] Upgrade: h2c [2025-06-28T11:08:33.923Z] User-Agent: Java-http-client/21.0.8-beta [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] TestServer(2): connection accepted [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.923Z] TestServer(2): accepting connections [2025-06-28T11:08:33.923Z] TestServer(2): creating connection [2025-06-28T11:08:33.923Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47764, Session(1751108892565|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.923Z] TestServer(2): connection accepted [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.923Z] TestServer(2): accepting connections [2025-06-28T11:08:33.923Z] TestServer(2): creating connection [2025-06-28T11:08:33.923Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47768, Session(1751108892649|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:12 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] TestServer(2): connection accepted [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.923Z] TestServer(2): accepting connections [2025-06-28T11:08:33.923Z] TestServer(2): connection accepted [2025-06-28T11:08:33.923Z] TestServer(2): creating connection [2025-06-28T11:08:33.923Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47778, Session(1751108892858|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.923Z] TestServer(2): accepting connections [2025-06-28T11:08:33.923Z] TestServer(2): creating connection [2025-06-28T11:08:33.923Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47780, Session(1751108893247|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.923Z] TestServer(2): connection accepted [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.923Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.923Z] connection: Upgrade [2025-06-28T11:08:33.923Z] upgrade: h2c [2025-06-28T11:08:33.923Z] [2025-06-28T11:08:33.923Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.924Z] TestServer(2): accepting connections [2025-06-28T11:08:33.924Z] TestServer(2): creating connection [2025-06-28T11:08:33.924Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47786, Session(1751108893261|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(2): connection accepted [2025-06-28T11:08:33.924Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.924Z] TestServer(2): accepting connections [2025-06-28T11:08:33.924Z] TestServer(2): creating connection [2025-06-28T11:08:33.924Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47790, Session(1751108893625|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:13 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(2): connection accepted [2025-06-28T11:08:33.924Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.924Z] TestServer(2): accepting connections [2025-06-28T11:08:33.924Z] TestServer(2): creating connection [2025-06-28T11:08:33.924Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47794, Session(1751108893841|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(2): connection accepted [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=22 [2025-06-28T11:08:33.924Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(1): update sendWindow (window=65535, amount=33488897) is now: 33554432 [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=16 [2025-06-28T11:08:33.924Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.924Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=40 [2025-06-28T11:08:33.924Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.924Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:14 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] TestServer(1): connection started [2025-06-28T11:08:33.924Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.924Z] TestServer(1): update sendWindow (window=65535, amount=33488897) is now: 33554432 [2025-06-28T11:08:33.924Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=34 [2025-06-28T11:08:33.924Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.924Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.924Z] Sent response headers 200 [2025-06-28T11:08:33.924Z] Sent response headers 200 [2025-06-28T11:08:33.924Z] Sent response headers 200 [2025-06-28T11:08:33.924Z] Sent response headers 200 [2025-06-28T11:08:33.924Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.924Z] transfer-encoding: chunked [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] connection: Upgrade [2025-06-28T11:08:33.924Z] upgrade: h2c [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.924Z] content-length: 0 [2025-06-28T11:08:33.924Z] [2025-06-28T11:08:33.924Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.924Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] connection: Upgrade [2025-06-28T11:08:33.925Z] upgrade: h2c [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] date: Sat, 28 Jun 2025 11:08:12 GMT [2025-06-28T11:08:33.925Z] transfer-encoding: chunked [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] connection: Upgrade [2025-06-28T11:08:33.925Z] upgrade: h2c [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] connection: Upgrade [2025-06-28T11:08:33.925Z] upgrade: h2c [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] connection: Upgrade [2025-06-28T11:08:33.925Z] upgrade: h2c [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.925Z] TestServer(2): accepting connections [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65510, amount=33488897) is now: 33554407 [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=46 [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=58 [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=52 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65535, amount=33488897) is now: 33554432 [2025-06-28T11:08:33.925Z] TestServer(2): creating connection [2025-06-28T11:08:33.925Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47798, Session(1751108894023|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=10 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=64 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=28 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65535, amount=33488897) is now: 33554432 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65535, amount=33488897) is now: 33554432 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=76 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:15 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=94 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65460, amount=33488897) is now: 33554357 [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=4 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.925Z] TestServer(2): connection accepted [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65510, amount=33488897) is now: 33554407 [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65510, amount=33488897) is now: 33554407 [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] content-length: 0 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=70 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=100 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=106 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=118 [2025-06-28T11:08:33.925Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.925Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.925Z] Sent response headers 200 [2025-06-28T11:08:33.925Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] TestServer(1): connection started [2025-06-28T11:08:33.925Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] :status: 200 [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.925Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.925Z] date: Sat, 28 Jun 2025 11:08:16 GMT [2025-06-28T11:08:33.925Z] transfer-encoding: chunked [2025-06-28T11:08:33.925Z] [2025-06-28T11:08:33.925Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] date: Sat, 28 Jun 2025 11:08:16 GMT [2025-06-28T11:08:33.926Z] transfer-encoding: chunked [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] date: Sat, 28 Jun 2025 11:08:16 GMT [2025-06-28T11:08:33.926Z] transfer-encoding: chunked [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:16 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.926Z] TestServer(2): accepting connections [2025-06-28T11:08:33.926Z] TestServer(2): creating connection [2025-06-28T11:08:33.926Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47802, Session(1751108895817|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] content-length: 0 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=88 [2025-06-28T11:08:33.926Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.926Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.926Z] Sent response headers 200 [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65427, amount=33488897) is now: 33554324 [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65460, amount=33488897) is now: 33554357 [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65460, amount=33488897) is now: 33554357 [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=112 [2025-06-28T11:08:33.926Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.926Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.926Z] Sent response headers 200 [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65510, amount=33488897) is now: 33554407 [2025-06-28T11:08:33.926Z] TestServer(1): GET /HttpSlowServerTest/http2/direct/foo?n=82 [2025-06-28T11:08:33.926Z] TestServer(1): creating input stream for stream 1 [2025-06-28T11:08:33.926Z] TestServer(1): Using handler for: /HttpSlowServerTest/http2/ [2025-06-28T11:08:33.926Z] Sent response headers 200 [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65524, amount=33488897) is now: 33554421 [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): update sendWindow (window=65510, amount=33488897) is now: 33554407 [2025-06-28T11:08:33.926Z] TestServer(2): connection accepted [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Http1Response lambda$readHeadersAsync$0 [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] content-length: 0 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:17 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] TestServer(1): connection started [2025-06-28T11:08:33.926Z] TestServer(1): writing HEADERS: length=4, streamid=1, flags=END_HEADERS [2025-06-28T11:08:33.926Z] TestServer(2): stopping is: false [2025-06-28T11:08:33.926Z] TestServer(2): accepting connections [2025-06-28T11:08:33.926Z] TestServer(2): creating connection [2025-06-28T11:08:33.926Z] TestServer(2): New connection from SSLSocket[hostname=127.0.0.1, port=47808, Session(1751108896957|SSL_NULL_WITH_NULL_NULL)] [2025-06-28T11:08:33.926Z] Jun 28, 2025 11:08:18 AM jdk.internal.net.http.Stream handleResponse [2025-06-28T11:08:33.926Z] INFO: HEADERS: RESPONSE HEADERS: [2025-06-28T11:08:33.926Z] :status: 200 [2025-06-28T11:08:33.926Z] [2025-06-28T11:08:33.926Z] Fatal glibc error: pthread_mutex_lock.c:94 (___pthread_mutex_lock): assertion failed: mutex->__data.__owner == 0 [2025-06-28T11:08:33.926Z] rerun: [2025-06-28T11:08:33.926Z] cd /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/scratch/0 && \ [2025-06-28T11:08:33.926Z] DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1001/bus \ [2025-06-28T11:08:33.926Z] DISPLAY=:0 \ [2025-06-28T11:08:33.926Z] HOME=/home/jenkins \ [2025-06-28T11:08:33.926Z] LANG=en_US.UTF-8 \ [2025-06-28T11:08:33.926Z] PATH=/bin:/usr/bin:/usr/sbin \ [2025-06-28T11:08:33.926Z] XDG_RUNTIME_DIR=/run/user/1001 \ [2025-06-28T11:08:33.926Z] XDG_SESSION_CLASS=user \ [2025-06-28T11:08:33.926Z] XDG_SESSION_ID=41739 \ [2025-06-28T11:08:33.926Z] XDG_SESSION_TYPE=tty \ [2025-06-28T11:08:33.926Z] CLASSPATH=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jvmtest/openjdk/jtreg/lib/javatest.jar:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jvmtest/openjdk/jtreg/lib/jtreg.jar \ [2025-06-28T11:08:33.926Z] /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image/bin/java \ [2025-06-28T11:08:33.926Z] -Dtest.vm.opts='-ea -esa -Xmx512m -XX:+UseCompressedOops -Djava.awt.headless=true' \ [2025-06-28T11:08:33.926Z] -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m -J-XX:+UseCompressedOops -J-Djava.awt.headless=true' \ [2025-06-28T11:08:33.926Z] -Dtest.compiler.opts= \ [2025-06-28T11:08:33.926Z] -Dtest.java.opts= \ [2025-06-28T11:08:33.926Z] -Dtest.jdk=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-28T11:08:33.926Z] -Dcompile.jdk=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/j2sdk-image \ [2025-06-28T11:08:33.926Z] -Dtest.timeout.factor=16.0 \ [2025-06-28T11:08:33.926Z] -Dtest.nativepath=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-06-28T11:08:33.926Z] -Dtest.root=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk \ [2025-06-28T11:08:33.926Z] -Dtest.name=java/net/httpclient/HttpSlowServerTest.java \ [2025-06-28T11:08:33.926Z] -Dtest.file=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/HttpSlowServerTest.java \ [2025-06-28T11:08:33.926Z] -Dtest.src=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient \ [2025-06-28T11:08:33.926Z] -Dtest.src.path=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.926Z] -Dtest.classes=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d \ [2025-06-28T11:08:33.927Z] -Dtest.class.path=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.927Z] -Dtest.class.path.prefix=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/java/net/httpclient/HttpSlowServerTest.d:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/openjdk/openjdk-jdk/test/jdk/java/net/httpclient:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/lib:/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/classes/1/test/jdk/java/net/httpclient/lib \ [2025-06-28T11:08:33.927Z] -Dtest.modules='java.base/sun.net.www.http java.base/sun.net.www java.base/sun.net java.net.http/jdk.internal.net.http.common java.net.http/jdk.internal.net.http.frame java.net.http/jdk.internal.net.http.hpack java.logging jdk.httpserver' \ [2025-06-28T11:08:33.927Z] --add-modules java.base,java.net.http,java.logging,jdk.httpserver \ [2025-06-28T11:08:33.927Z] --add-exports java.base/sun.net.www.http=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] --add-exports java.base/sun.net.www=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] --add-exports java.base/sun.net=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] --add-exports java.net.http/jdk.internal.net.http.common=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] --add-exports java.net.http/jdk.internal.net.http.frame=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] --add-exports java.net.http/jdk.internal.net.http.hpack=ALL-UNNAMED \ [2025-06-28T11:08:33.927Z] -ea \ [2025-06-28T11:08:33.927Z] -esa \ [2025-06-28T11:08:33.927Z] -Xmx512m \ [2025-06-28T11:08:33.927Z] -XX:+UseCompressedOops \ [2025-06-28T11:08:33.927Z] -Djava.awt.headless=true \ [2025-06-28T11:08:33.927Z] -Djava.library.path=/home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/jdkbinary/openjdk-test-image/jdk/jtreg/native \ [2025-06-28T11:08:33.927Z] -Dtest.requiresHost=true \ [2025-06-28T11:08:33.927Z] -Djdk.httpclient.HttpClient.log=headers \ [2025-06-28T11:08:33.927Z] -Djdk.internal.httpclient.debug=false \ [2025-06-28T11:08:33.927Z] com.sun.javatest.regtest.agent.MainWrapper /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work/java/net/httpclient/HttpSlowServerTest.d/main.0.jta [2025-06-28T11:08:33.927Z] [2025-06-28T11:08:33.927Z] TEST RESULT: Failed. Unexpected exit from test [exit code: 134] [2025-06-28T11:08:33.927Z] -------------------------------------------------- [2025-06-28T11:53:01.948Z] Test results: passed: 884; failed: 1 [2025-06-28T11:53:50.160Z] Report written to /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/report/html/report.html [2025-06-28T11:53:50.160Z] Results written to /home/jenkins/workspace/Test_openjdk21_hs_extended.openjdk_riscv64_linux_testList_2/aqa-tests/TKG/output_17510971267734/jdk_net_0/work [2025-06-28T11:53:50.160Z] Error: Some tests failed or other problems occurred. [2025-06-28T11:53:50.160Z] ----------------------------------- [2025-06-28T11:53:50.160Z] jdk_net_0_FAILED [2025-06-28T11:53:50.161Z] ----------------------------------- [2025-06-28T11:53:50.161Z] [2025-06-28T11:53:50.161Z] TEST TEARDOWN: [2025-06-28T11:53:50.161Z] Nothing to be done for teardown. [2025-06-28T11:53:50.161Z] jdk_net_0 Finish Time: Sat Jun 28 11:53:46 2025 Epoch Time (ms): 1751111626475