Open karianna opened 2 years ago
Failing regularly on tip and recently Java 19 release:
e.g., (from an earlier run)
[2022-09-20T17:36:45.258Z] Running test HCRLateAttachWorkload_previewEnabled_1 ... [2022-09-20T17:36:45.258Z] =============================================== [2022-09-20T17:36:45.258Z] HCRLateAttachWorkload_previewEnabled_1 Start Time: Tue Sep 20 12:36:12 2022 Epoch Time (ms): 1663695372698 [2022-09-20T17:36:45.258Z] Nothing to be done for setup. [2022-09-20T17:36:45.842Z] variation: Mode650 [2022-09-20T17:36:45.842Z] JVM_OPTIONS: -XX:-UseCompressedOops [2022-09-20T17:36:45.842Z] { itercnt=1; \ [2022-09-20T17:36:45.842Z] mkdir -p "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; \ [2022-09-20T17:36:45.842Z] cd "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; \ [2022-09-20T17:36:45.842Z] perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\STF\stf.core\scripts\stf.pl -test-root="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\STF;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\aqa-systemtest""" -systemtest-prereqs="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs" -java-args=' -XX:-UseCompressedOops ' -results-root="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1" -debug-generation="true" -java-debug-args="--enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED" -java-args-execute-initial="--enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED" -test=HCRLateAttachWorkload; \ [2022-09-20T17:36:45.842Z] if [ $? -eq 0 ] ; then echo ""; echo "HCRLateAttachWorkload_previewEnabled_1""_PASSED"; echo ""; cd E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/; rm -f -r "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; else echo ""; echo "HCRLateAttachWorkload_previewEnabled_1""_FAILED"; echo ""; fi; } 2>&1 | tee -a "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\TestTargetResult"; [2022-09-20T17:36:45.842Z] STF 12:36:13.086 - ========================= S T F ========================= [2022-09-20T17:36:45.842Z] systemtest-prereqs has been processed, and set to: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqsRetrieving amount of free space on drive containing E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1 [2022-09-20T17:36:45.842Z] There is 454504 Mb free [2022-09-20T17:36:45.842Z] STF 12:36:13.110 - ================== G E N E R A T I O N ================== [2022-09-20T17:36:45.842Z] STF 12:36:13.113 - Checking JVM: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image [2022-09-20T17:36:45.842Z] STF 12:36:13.113 - Starting process to generate scripts: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs/log4j/log4j-api.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs/log4j/log4j-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/stf_parameters.properties, , E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload" [2022-09-20T17:36:45.842Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:45.842Z] GEN stderr OpenJDK 64-Bit Server VM warning: Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2022-09-20T17:36:46.987Z] GEN 12:36:14.019 - Found test. Project: 'openjdk.test.debugging' class: 'HCRLateAttachWorkload.class' Dir: 'E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.debugging\bin' [2022-09-20T17:36:46.987Z] GEN 12:36:14.034 - Found test. Project: 'openjdk.test.debugging' class: 'net.adoptopenjdk.stf.hcrAgent.HCRLateAttachWorkload' [2022-09-20T17:36:46.987Z] GEN 12:36:14.179 - openjdk.test.debugging: Ignoring classpath tools.jar entry because test is not executing against Java 8 [2022-09-20T17:36:46.987Z] GEN Classpath directories used by project 'openjdk.test.debugging': [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.debugging\bin [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.core\bin [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.load\bin [2022-09-20T17:36:46.987Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar [2022-09-20T17:36:46.987Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar [2022-09-20T17:36:46.988Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/junit.jar [2022-09-20T17:36:46.988Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/asm/asm.jar [2022-09-20T17:36:46.988Z] GEN 12:36:14.213 - Using Mode NoOptions. Values = '' [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - Test command summary: [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - Step Stage Command Description [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - -----+--------+-----------------+------------ [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 1 execute Run java Run load test [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 2 execute Run java Run StringModifierAgent [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 3 execute Monitor Wait for Agent to complete [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 4 execute kill Stop LT process [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - Script generation completed [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - ======================= S E T U P ======================= [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - Running setup: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/setUp.pl [2022-09-20T17:36:46.988Z] STF 12:36:14.413 - SETUP stage completed [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - ==================== E X E C U T E - ==================== [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - Running execute: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/execute.pl [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - Java version [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - Running: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java -version [2022-09-20T17:36:47.532Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.532Z] openjdk version "19" 2022-09-20 [2022-09-20T17:36:47.532Z] OpenJDK Runtime Environment Temurin-19+36 (build 19+36) [2022-09-20T17:36:47.532Z] OpenJDK 64-Bit Server VM Temurin-19+36 (build 19+36, mixed mode, sharing) [2022-09-20T17:36:47.532Z] STF 12:36:14.640 - [2022-09-20T17:36:47.532Z] STF 12:36:14.640 - +------ Step 1 - Run load test [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Start background process [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Program: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Mnemonic: LT [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Echo: ECHO_ON [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Expectation: NEVER [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.641 - Running command: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -XX:-UseCompressedOops -classpath E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.load\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.core\bin;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/junit.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/asm/asm.jar;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results -resultsPrefix 1.LT. -timeLimit 10m -reportFailureLimit 1 -abortAtFailureLimit 10 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.util.inventoryFile E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util.xml -suite.util.inventoryExcludeFile E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.util.threadCount 3 -suite.util.selection sequential -suite.util.seed -1 -suite.util.repeatCount 1 -suite.util.thinkingTime 0ms..0ms [2022-09-20T17:36:47.533Z] STF 12:36:14.641 - Redirecting stderr to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.stderr [2022-09-20T17:36:47.533Z] STF 12:36:14.642 - Redirecting stdout to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.stdout [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - +------ Step 2 - Run StringModifierAgent [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Start background process [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Program: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Mnemonic: AG [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Echo: ECHO_ON [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Expectation: CLEAN_RUN within 10m [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Running command: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -XX:-UseCompressedOops -classpath E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/lib/tools.jar net.adoptopenjdk.test.hcrAgent.agent.Attacher 14668 E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar duration=240 [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Redirecting stderr to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/2.AG.stderr [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Redirecting stdout to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/2.AG.stdout [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - +------ Step 3 - Wait for Agent to complete [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | Wait for processes to meet expectations [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | Processes: [LT, AG] [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - Monitoring processes: AG LT [2022-09-20T17:36:47.533Z] AG stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.533Z] LT stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.533Z] AG 12:36:14 > Will attempt to attach to JVM with pid 14668 [2022-09-20T17:36:47.533Z] AG 12:36:14 > Attach will be done using agent jar file: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar [2022-09-20T17:36:48.076Z] LT The agent's agentmain method was called. [2022-09-20T17:36:48.076Z] LT To re-run this agent with these options, pass this option to the agent on the command line: "percentage=50,threads=10,threadsTimeout=300000,duration=240,seed=7592475417417258389" [2022-09-20T17:36:48.076Z] LT Initialising all of the threads. [2022-09-20T17:36:48.076Z] LT Starting thread 0 [2022-09-20T17:36:48.076Z] LT Starting thread 1 [2022-09-20T17:36:48.076Z] LT Starting thread 2 [2022-09-20T17:36:48.076Z] LT Starting thread 3 [2022-09-20T17:36:48.076Z] LT Starting thread 4 [2022-09-20T17:36:48.076Z] LT Starting thread 5 [2022-09-20T17:36:48.076Z] LT Starting thread 6 [2022-09-20T17:36:48.076Z] LT Starting thread 7 [2022-09-20T17:36:48.076Z] LT Starting thread 8 [2022-09-20T17:36:48.076Z] LT Starting thread 9 [2022-09-20T17:36:48.076Z] LT Threads are all initialised. [2022-09-20T17:36:48.076Z] LT Entering the loop that will replace dead threads with live ones until we run out of time. [2022-09-20T17:36:48.620Z] LT 12:36:15.558 - Load test parameters [2022-09-20T17:36:48.620Z] LT 12:36:15.561 - Time limited = true [2022-09-20T17:36:48.620Z] LT 12:36:15.561 - Time limit = 10m [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - abortIfOutOfMemory = true [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - reportFailureLimit = 1 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - abortAtFailureLimit = 10 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - maxTotalLogFileSpace = 209715200 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - maxSingleLogSize = 8388608 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - Parameters for suite 0 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - Suite name = util [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Number threads = 3 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Supplied seed = -1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Inventory file = E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Exclude file = E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Number tests = -1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Repeat count = 1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Thinking time = 0ms..0ms [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Selection mode = sequential [2022-09-20T17:36:48.620Z] LT 12:36:15.571 - Parsing inventory file. Root=E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results\1.LT.inventory File=openjdk.test.load/config/inventories/util/util.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.578 - Reading exclusion file. File=openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.578 - Parsing inventory file. Root=E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results\1.LT.inventory File=openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.580 - Final test list: [2022-09-20T17:36:48.620Z] LT 12:36:15.586 - 0 ArbitraryJava[net.adoptopenjdk.test.util.calendars.CalendarTest run] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.586 - 1 ArbitraryJava[net.adoptopenjdk.test.util.hashmap.Hog run] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 2 JUnit[net.adoptopenjdk.test.util.lilac.TestArrays] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 4 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapAPITest] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 5 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapTest] Weighting=1 [2022-09-20T17:36:48.620Z] LT stderr WARNING: A terminally deprecated method in java.lang.System has been called [2022-09-20T17:36:48.620Z] LT stderr WARNING: System::setSecurityManager has been called by net.adoptopenjdk.loadTest.LoadTest (file:/E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.load/bin/) [2022-09-20T17:36:48.620Z] LT stderr WARNING: Please consider reporting this to the maintainers of net.adoptopenjdk.loadTest.LoadTest [2022-09-20T17:36:48.620Z] LT stderr WARNING: System::setSecurityManager will be removed in a future release [2022-09-20T17:36:48.620Z] LT 12:36:15.672 - Starting thread. Suite=0 thread=0 [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-1" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "main" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-7" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-8" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "pool-1-thread-1" [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - FAILED Process LT has ended unexpectedly [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - Monitoring Report Summary: [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process AG is still running [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process LT has ended unexpectedly [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - Killing processes: AG LT [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process LT pid 14668 is not running [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process clean up attempt 1 for AG pid 11240 [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process AG pid 11240 stop() [2022-09-20T17:36:48.620Z] STF 12:36:15.802 - o Process AG pid 11240 killed [2022-09-20T17:36:48.620Z] FAILED at step 3 (Wait for Agent to complete). Expected return value=0 Actual=1 at E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/execute.pl line 156. [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - FAILED execute script failed. Expected return value=0 Actual=1 [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - ==================== T E A R D O W N ==================== [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - Running teardown: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/tearDown.pl [2022-09-20T17:36:48.620Z] STF 12:36:15.915 - TEARDOWN stage completed [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - ===================== R E S U L T S ===================== [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - Stage results: [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - setUp: pass [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - execute: fail [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - teardown: pass [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - [2022-09-20T17:36:48.621Z] STF 12:36:15.923 - Overall result: FAILED [2022-09-20T17:36:48.621Z] [2022-09-20T17:36:48.621Z] HCRLateAttachWorkload_previewEnabled_1_FAILED [2022-09-20T17:36:48.621Z] [2022-09-20T17:36:48.621Z] Nothing to be done for teardown. [2022-09-20T17:36:48.621Z] HCRLateAttachWorkload_previewEnabled_1 Finish Time: Tue Sep 20 12:36:16 2022 Epoch Time (ms): 1663695376061
Also see https://github.com/adoptium/aqa-tests/pull/3750 where OpenJ9 excluded the test
Failing regularly on tip and recently Java 19 release:
e.g., (from an earlier run)
[2022-09-20T17:36:45.258Z] Running test HCRLateAttachWorkload_previewEnabled_1 ... [2022-09-20T17:36:45.258Z] =============================================== [2022-09-20T17:36:45.258Z] HCRLateAttachWorkload_previewEnabled_1 Start Time: Tue Sep 20 12:36:12 2022 Epoch Time (ms): 1663695372698 [2022-09-20T17:36:45.258Z] Nothing to be done for setup. [2022-09-20T17:36:45.842Z] variation: Mode650 [2022-09-20T17:36:45.842Z] JVM_OPTIONS: -XX:-UseCompressedOops [2022-09-20T17:36:45.842Z] { itercnt=1; \ [2022-09-20T17:36:45.842Z] mkdir -p "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; \ [2022-09-20T17:36:45.842Z] cd "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; \ [2022-09-20T17:36:45.842Z] perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\STF\stf.core\scripts\stf.pl -test-root="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\STF;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\aqa-systemtest""" -systemtest-prereqs="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs" -java-args=' -XX:-UseCompressedOops ' -results-root="E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1" -debug-generation="true" -java-debug-args="--enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED" -java-args-execute-initial="--enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED" -test=HCRLateAttachWorkload; \ [2022-09-20T17:36:45.842Z] if [ $? -eq 0 ] ; then echo ""; echo "HCRLateAttachWorkload_previewEnabled_1""_PASSED"; echo ""; cd E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/; rm -f -r "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1"; else echo ""; echo "HCRLateAttachWorkload_previewEnabled_1""_FAILED"; echo ""; fi; } 2>&1 | tee -a "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\TestTargetResult"; [2022-09-20T17:36:45.842Z] STF 12:36:13.086 - ========================= S T F ========================= [2022-09-20T17:36:45.842Z] systemtest-prereqs has been processed, and set to: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqsRetrieving amount of free space on drive containing E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1 [2022-09-20T17:36:45.842Z] There is 454504 Mb free [2022-09-20T17:36:45.842Z] STF 12:36:13.110 - ================== G E N E R A T I O N ================== [2022-09-20T17:36:45.842Z] STF 12:36:13.113 - Checking JVM: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image [2022-09-20T17:36:45.842Z] STF 12:36:13.113 - Starting process to generate scripts: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -Dlog4j.skipJansi=true -Djava.system.class.loader=net.adoptopenjdk.stf.runner.StfClassLoader -classpath E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs/log4j/log4j-api.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests///..//jvmtest\system\otherLoadTest/../\systemtest_prereqs/log4j/log4j-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.core/scripts/../bin net.adoptopenjdk.stf.runner.StfRunner -properties "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/stf_parameters.properties, , E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.core/config/stf.properties" -testDir "E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload" [2022-09-20T17:36:45.842Z] GEN stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:45.842Z] GEN stderr OpenJDK 64-Bit Server VM warning: Archived non-system classes are disabled because the java.system.class.loader property is specified (value = "net.adoptopenjdk.stf.runner.StfClassLoader"). To use archived non-system classes, this property must not be set [2022-09-20T17:36:46.987Z] GEN 12:36:14.019 - Found test. Project: 'openjdk.test.debugging' class: 'HCRLateAttachWorkload.class' Dir: 'E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.debugging\bin' [2022-09-20T17:36:46.987Z] GEN 12:36:14.034 - Found test. Project: 'openjdk.test.debugging' class: 'net.adoptopenjdk.stf.hcrAgent.HCRLateAttachWorkload' [2022-09-20T17:36:46.987Z] GEN 12:36:14.179 - openjdk.test.debugging: Ignoring classpath tools.jar entry because test is not executing against Java 8 [2022-09-20T17:36:46.987Z] GEN Classpath directories used by project 'openjdk.test.debugging': [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.debugging\bin [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.core\bin [2022-09-20T17:36:46.987Z] GEN E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.load\bin [2022-09-20T17:36:46.987Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar [2022-09-20T17:36:46.987Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar [2022-09-20T17:36:46.988Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/junit.jar [2022-09-20T17:36:46.988Z] GEN E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/asm/asm.jar [2022-09-20T17:36:46.988Z] GEN 12:36:14.213 - Using Mode NoOptions. Values = '' [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - Test command summary: [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - Step Stage Command Description [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - -----+--------+-----------------+------------ [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 1 execute Run java Run load test [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 2 execute Run java Run StringModifierAgent [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 3 execute Monitor Wait for Agent to complete [2022-09-20T17:36:46.988Z] GEN 12:36:14.265 - 4 execute kill Stop LT process [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - Script generation completed [2022-09-20T17:36:46.988Z] STF 12:36:14.314 - [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - ======================= S E T U P ======================= [2022-09-20T17:36:46.988Z] STF 12:36:14.315 - Running setup: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/setUp.pl [2022-09-20T17:36:46.988Z] STF 12:36:14.413 - SETUP stage completed [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - ==================== E X E C U T E - ==================== [2022-09-20T17:36:46.988Z] STF 12:36:14.423 - Running execute: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/execute.pl [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - Java version [2022-09-20T17:36:47.532Z] STF 12:36:14.529 - Running: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java -version [2022-09-20T17:36:47.532Z] Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.532Z] openjdk version "19" 2022-09-20 [2022-09-20T17:36:47.532Z] OpenJDK Runtime Environment Temurin-19+36 (build 19+36) [2022-09-20T17:36:47.532Z] OpenJDK 64-Bit Server VM Temurin-19+36 (build 19+36, mixed mode, sharing) [2022-09-20T17:36:47.532Z] STF 12:36:14.640 - [2022-09-20T17:36:47.532Z] STF 12:36:14.640 - +------ Step 1 - Run load test [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Start background process [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Program: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Mnemonic: LT [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Echo: ECHO_ON [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | Expectation: NEVER [2022-09-20T17:36:47.532Z] STF 12:36:14.641 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.641 - Running command: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -XX:-UseCompressedOops -classpath E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.load\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\STF\stf.core\bin;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-api.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/log4j/log4j-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/junit.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/junit/hamcrest-core.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/systemtest_prereqs/asm/asm.jar;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.lang\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.util\bin;E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\jvmtest\system\aqa-systemtest\openjdk.test.math\bin net.adoptopenjdk.loadTest.LoadTest -resultsDir E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results -resultsPrefix 1.LT. -timeLimit 10m -reportFailureLimit 1 -abortAtFailureLimit 10 -maxTotalLogFileSpace 200M -maxSingleLogSize 1/25 -suite.util.inventoryFile E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util.xml -suite.util.inventoryExcludeFile E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml -suite.util.threadCount 3 -suite.util.selection sequential -suite.util.seed -1 -suite.util.repeatCount 1 -suite.util.thinkingTime 0ms..0ms [2022-09-20T17:36:47.533Z] STF 12:36:14.641 - Redirecting stderr to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.stderr [2022-09-20T17:36:47.533Z] STF 12:36:14.642 - Redirecting stdout to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.stdout [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - +------ Step 2 - Run StringModifierAgent [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Start background process [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Program: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Mnemonic: AG [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Echo: ECHO_ON [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | Expectation: CLEAN_RUN within 10m [2022-09-20T17:36:47.533Z] STF 12:36:14.672 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Running command: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/bin/java --enable-preview --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED -XX:-UseCompressedOops -classpath E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar;E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/openjdkbinary/j2sdk-image/lib/tools.jar net.adoptopenjdk.test.hcrAgent.agent.Attacher 14668 E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar duration=240 [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Redirecting stderr to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/2.AG.stderr [2022-09-20T17:36:47.533Z] STF 12:36:14.673 - Redirecting stdout to E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/2.AG.stdout [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - +------ Step 3 - Wait for Agent to complete [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | Wait for processes to meet expectations [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | Processes: [LT, AG] [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - | [2022-09-20T17:36:47.533Z] STF 12:36:14.674 - Monitoring processes: AG LT [2022-09-20T17:36:47.533Z] AG stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.533Z] LT stderr Picked up JAVA_TOOL_OPTIONS: -Djava.security.manager=allow [2022-09-20T17:36:47.533Z] AG 12:36:14 > Will attempt to attach to JVM with pid 14668 [2022-09-20T17:36:47.533Z] AG 12:36:14 > Attach will be done using agent jar file: E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/aqa-systemtest/openjdk.test.debugging/bin/StringModifierAgent.jar [2022-09-20T17:36:48.076Z] LT The agent's agentmain method was called. [2022-09-20T17:36:48.076Z] LT To re-run this agent with these options, pass this option to the agent on the command line: "percentage=50,threads=10,threadsTimeout=300000,duration=240,seed=7592475417417258389" [2022-09-20T17:36:48.076Z] LT Initialising all of the threads. [2022-09-20T17:36:48.076Z] LT Starting thread 0 [2022-09-20T17:36:48.076Z] LT Starting thread 1 [2022-09-20T17:36:48.076Z] LT Starting thread 2 [2022-09-20T17:36:48.076Z] LT Starting thread 3 [2022-09-20T17:36:48.076Z] LT Starting thread 4 [2022-09-20T17:36:48.076Z] LT Starting thread 5 [2022-09-20T17:36:48.076Z] LT Starting thread 6 [2022-09-20T17:36:48.076Z] LT Starting thread 7 [2022-09-20T17:36:48.076Z] LT Starting thread 8 [2022-09-20T17:36:48.076Z] LT Starting thread 9 [2022-09-20T17:36:48.076Z] LT Threads are all initialised. [2022-09-20T17:36:48.076Z] LT Entering the loop that will replace dead threads with live ones until we run out of time. [2022-09-20T17:36:48.620Z] LT 12:36:15.558 - Load test parameters [2022-09-20T17:36:48.620Z] LT 12:36:15.561 - Time limited = true [2022-09-20T17:36:48.620Z] LT 12:36:15.561 - Time limit = 10m [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - abortIfOutOfMemory = true [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - reportFailureLimit = 1 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - abortAtFailureLimit = 10 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - maxTotalLogFileSpace = 209715200 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - maxSingleLogSize = 8388608 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - Parameters for suite 0 [2022-09-20T17:36:48.620Z] LT 12:36:15.562 - Suite name = util [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Number threads = 3 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Supplied seed = -1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Inventory file = E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Exclude file = E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/TKG/output_16636907202560/HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/results/1.LT.inventory/openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Number tests = -1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Repeat count = 1 [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Thinking time = 0ms..0ms [2022-09-20T17:36:48.620Z] LT 12:36:15.564 - Selection mode = sequential [2022-09-20T17:36:48.620Z] LT 12:36:15.571 - Parsing inventory file. Root=E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results\1.LT.inventory File=openjdk.test.load/config/inventories/util/util.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.578 - Reading exclusion file. File=openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.578 - Parsing inventory file. Root=E:\workspace\Test_openjdk19_hs_extended.system_x86-64_windows\aqa-tests\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1\20220920-123613-HCRLateAttachWorkload\results\1.LT.inventory File=openjdk.test.load/config/inventories/util/util_exclude.xml [2022-09-20T17:36:48.620Z] LT 12:36:15.580 - Final test list: [2022-09-20T17:36:48.620Z] LT 12:36:15.586 - 0 ArbitraryJava[net.adoptopenjdk.test.util.calendars.CalendarTest run] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.586 - 1 ArbitraryJava[net.adoptopenjdk.test.util.hashmap.Hog run] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 2 JUnit[net.adoptopenjdk.test.util.lilac.TestArrays] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 4 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapAPITest] Weighting=1 [2022-09-20T17:36:48.620Z] LT 12:36:15.587 - 5 JUnit[net.adoptopenjdk.test.util.treemap.TreeMapTest] Weighting=1 [2022-09-20T17:36:48.620Z] LT stderr WARNING: A terminally deprecated method in java.lang.System has been called [2022-09-20T17:36:48.620Z] LT stderr WARNING: System::setSecurityManager has been called by net.adoptopenjdk.loadTest.LoadTest (file:/E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/jvmtest/system/STF/stf.load/bin/) [2022-09-20T17:36:48.620Z] LT stderr WARNING: Please consider reporting this to the maintainers of net.adoptopenjdk.loadTest.LoadTest [2022-09-20T17:36:48.620Z] LT stderr WARNING: System::setSecurityManager will be removed in a future release [2022-09-20T17:36:48.620Z] LT 12:36:15.672 - Starting thread. Suite=0 thread=0 [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-1" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "main" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-7" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "Thread-8" [2022-09-20T17:36:48.620Z] LT stderr [2022-09-20T17:36:48.620Z] LT stderr Exception: java.lang.StackOverflowError thrown from the UncaughtExceptionHandler in thread "pool-1-thread-1" [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - FAILED Process LT has ended unexpectedly [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - Monitoring Report Summary: [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process AG is still running [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process LT has ended unexpectedly [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - Killing processes: AG LT [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process LT pid 14668 is not running [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process clean up attempt 1 for AG pid 11240 [2022-09-20T17:36:48.620Z] STF 12:36:15.801 - o Process AG pid 11240 stop() [2022-09-20T17:36:48.620Z] STF 12:36:15.802 - o Process AG pid 11240 killed [2022-09-20T17:36:48.620Z] FAILED at step 3 (Wait for Agent to complete). Expected return value=0 Actual=1 at E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/execute.pl line 156. [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - FAILED execute script failed. Expected return value=0 Actual=1 [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - ==================== T E A R D O W N ==================== [2022-09-20T17:36:48.620Z] STF 12:36:15.822 - Running teardown: perl E:/workspace/Test_openjdk19_hs_extended.system_x86-64_windows/aqa-tests/\TKG\output_16636907202560\HCRLateAttachWorkload_previewEnabled_1/20220920-123613-HCRLateAttachWorkload/tearDown.pl [2022-09-20T17:36:48.620Z] STF 12:36:15.915 - TEARDOWN stage completed [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - ===================== R E S U L T S ===================== [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - Stage results: [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - setUp: pass [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - execute: fail [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - teardown: pass [2022-09-20T17:36:48.620Z] STF 12:36:15.923 - [2022-09-20T17:36:48.621Z] STF 12:36:15.923 - Overall result: FAILED [2022-09-20T17:36:48.621Z] [2022-09-20T17:36:48.621Z] HCRLateAttachWorkload_previewEnabled_1_FAILED [2022-09-20T17:36:48.621Z] [2022-09-20T17:36:48.621Z] Nothing to be done for teardown. [2022-09-20T17:36:48.621Z] HCRLateAttachWorkload_previewEnabled_1 Finish Time: Tue Sep 20 12:36:16 2022 Epoch Time (ms): 1663695376061