search

eclipse-openj9 / openj9

Eclipse OpenJ9: A Java Virtual Machine for OpenJDK that's optimized for small footprint, fast start-up, and high throughput. Builds on Eclipse OMR (https://github.com/eclipse/omr) and combines with the Extensions for OpenJDK for OpenJ9 repo.
Other
3.23k stars 712 forks source link

cmdLineTester_criu_nonPortableRestore CheckpointDeadlock Success conditions not found #19618

Open pshipton opened 1 month ago

pshipton commented 1 month ago

https://openj9-jenkins.osuosl.org/job/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/88 cmdLineTester_criu_nonPortableRestore_10

00:49:16  Testing: Create and Restore Criu Checkpoint Image once - CheckpointDeadlock
00:49:16  Test start time: 2024/06/04 00:49:13 Eastern Standard Time
00:49:16  Running command: bash /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/jdkbinary/j2sdk-image/bin/java " -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED" org.openj9.criu.DeadlockTest CheckpointDeadlock 1 false false
00:49:16  Time spent starting: 4 milliseconds
00:50:17  Time spent executing: 54528 milliseconds
00:50:17  Test result: FAILED
00:50:17  Output from test:
00:50:17   [OUT] start running script
00:50:17   [OUT] export GLIBC_TUNABLES=glibc.cpu.hwcaps=-XSAVEC,-XSAVE,-AVX2,-ERMS,-AVX,-AVX_Fast_Unaligned_Load
00:50:17   [OUT] export LD_BIND_NOT=on
00:50:17   [OUT] /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/jdkbinary/j2sdk-image/bin/java -XX:+EnableCRIUSupport  -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED -cp /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criu.jar org.openj9.criu.DeadlockTest CheckpointDeadlock 1
00:50:17   [OUT] Pre-checkpoint
00:50:17   [OUT] main: Tue Jun 04 00:49:40 EDT 2024, Performing CRIUSupport.checkpointJVM(), System.currentTimeMillis(): 1717476598388, System.nanoTime(): 100179210725040
00:50:17   [OUT] Precheckpoint hook inside monitor
00:50:17   [OUT] JVMJITM044W Some or all compiled code in the code cache invalidated post restore.
00:50:17   [OUT] TEST FAILED
00:50:17   [OUT] initiate restore
00:50:17   [OUT] Removed test output files
00:50:17   [OUT] finished script
00:50:17   [ERR] /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh: line 41: 1484409 Killed                  $2 -XX:+EnableCRIUSupport $3 -cp "$1/criu.jar" $4 $5 $6 > testOutput 2>&1
00:50:17  >> Success condition was not found: [Output match: User requested Java dump using]
00:50:17  >> Success condition was not found: [Output match: TEST PASSED]
00:50:17  >> Required condition was found: [Output match: Pre-checkpoint]
00:50:17  >> Failure condition was found: [Output match: Killed]
00:50:17  >> Failure condition was not found: [Output match: CRIU is not enabled]
00:50:17  >> Failure condition was not found: [Output match: Operation not permitted]
00:50:17  >> Success condition was not found: [Output match: Thread pid mismatch]
00:50:17  >> Success condition was not found: [Output match: do not match expected]
00:50:17  >> Success condition was not found: [Output match: Unable to create a thread:]
00:50:17  >> Failure condition was found: [Output match: TEST FAILED]
00:50:17  >> Failure condition was not found: [Output match: Could not dump the JVM processes, err=-70]
pshipton commented 1 month ago

@tajila fyi

pshipton commented 1 week ago

https://openj9-jenkins.osuosl.org/job/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/696 - ub22-aarch64-1 cmdLineTester_criu_nonPortableRestore_10

01:08:57  Testing: Create and Restore Criu Checkpoint Image once - CheckpointDeadlock
01:08:57  Test start time: 2024/06/26 05:08:52 Coordinated Universal Time
01:08:57  Running command: bash /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/jdkbinary/j2sdk-image/bin/java " -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED" org.openj9.criu.DeadlockTest CheckpointDeadlock 1 false false
01:08:57  Time spent starting: 6 milliseconds
01:10:09  Time spent executing: 72178 milliseconds
01:10:09  Test result: FAILED
01:10:09  Output from test:
01:10:09   [OUT] start running script
01:10:09   [OUT] export GLIBC_TUNABLES=glibc.cpu.hwcaps=-XSAVEC,-XSAVE,-AVX2,-ERMS,-AVX,-AVX_Fast_Unaligned_Load
01:10:09   [OUT] export LD_BIND_NOT=on
01:10:09   [OUT] /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/jdkbinary/j2sdk-image/bin/java -XX:+EnableCRIUSupport  -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED -cp /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criu.jar org.openj9.criu.DeadlockTest CheckpointDeadlock 1
01:10:09   [OUT] Pre-checkpoint
01:10:09   [OUT] main: Wed Jun 26 05:09:27 UTC 2024, Performing CRIUSupport.checkpointJVM(), System.currentTimeMillis(): 1719378567251, System.nanoTime(): 2002155077664178
01:10:09   [OUT] Precheckpoint hook inside monitor
01:10:09   [OUT] JVMJITM044W Some or all compiled code in the code cache invalidated post restore.
01:10:09   [OUT] TEST FAILED
01:10:09   [OUT] initiate restore
01:10:09   [OUT] Removed test output files
01:10:09   [OUT] finished script
01:10:09   [ERR] /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_aarch64_linux_Nightly_testList_1/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh: line 41: 4182119 Killed                  $2 -XX:+EnableCRIUSupport $3 -cp "$1/criu.jar" $4 $5 $6 > testOutput 2>&1
01:10:09  >> Success condition was not found: [Output match: User requested Java dump using]
01:10:09  >> Success condition was not found: [Output match: TEST PASSED]
01:10:09  >> Required condition was found: [Output match: Pre-checkpoint]
01:10:09  >> Failure condition was found: [Output match: Killed]
01:10:09  >> Failure condition was not found: [Output match: CRIU is not enabled]
01:10:09  >> Failure condition was not found: [Output match: Operation not permitted]
01:10:09  >> Success condition was not found: [Output match: Thread pid mismatch]
01:10:09  >> Success condition was not found: [Output match: do not match expected]
01:10:09  >> Success condition was not found: [Output match: Unable to create a thread:]
01:10:09  >> Failure condition was found: [Output match: TEST FAILED]
01:10:09  >> Failure condition was not found: [Output match: Could not dump the JVM processes, err=-70]
JasonFengJ9 commented 1 week ago

JDK22 aarch64_linux(sles15-aarch64-6)

[2024-06-22T18:22:11.284Z] variation: -XX:+DebugOnRestore -Xjit:count=0
[2024-06-22T18:22:11.284Z] JVM_OPTIONS:  -XX:+DebugOnRestore -Xjit:count=0 

[2024-06-22T18:39:25.468Z] Testing: Create and Restore Criu Checkpoint Image once - CheckpointDeadlock
[2024-06-22T18:39:25.468Z] Test start time: 2024/06/22 18:39:13 Coordinated Universal Time
[2024-06-22T18:39:25.468Z] Running command: bash /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java " -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED" org.openj9.criu.DeadlockTest CheckpointDeadlock 1 false false
[2024-06-22T18:39:25.468Z] Time spent starting: 5 milliseconds
[2024-06-22T18:40:41.359Z] Time spent executing: 79379 milliseconds
[2024-06-22T18:40:41.359Z] Test result: FAILED
[2024-06-22T18:40:41.359Z] Output from test:
[2024-06-22T18:40:41.359Z]  [OUT] start running script
[2024-06-22T18:40:41.359Z]  [OUT] export GLIBC_TUNABLES=glibc.cpu.hwcaps=-XSAVEC,-XSAVE,-AVX2,-ERMS,-AVX,-AVX_Fast_Unaligned_Load
[2024-06-22T18:40:41.359Z]  [OUT] export LD_BIND_NOT=on
[2024-06-22T18:40:41.359Z]  [OUT] /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/jdkbinary/j2sdk-image/bin/java -XX:+EnableCRIUSupport  -XX:+DebugOnRestore -Xjit:count=0  --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED -cp /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criu.jar org.openj9.criu.DeadlockTest CheckpointDeadlock 1
[2024-06-22T18:40:41.359Z]  [OUT] Pre-checkpoint
[2024-06-22T18:40:41.359Z]  [OUT] main: Sat Jun 22 18:39:51 UTC 2024, Performing CRIUSupport.checkpointJVM(), System.currentTimeMillis(): 1719081609416, System.nanoTime(): 911980863383290
[2024-06-22T18:40:41.359Z]  [OUT] Precheckpoint hook inside monitor
[2024-06-22T18:40:41.359Z]  [OUT] JVMJITM044W Some or all compiled code in the code cache invalidated post restore.
[2024-06-22T18:40:41.359Z]  [OUT] TEST FAILED
[2024-06-22T18:40:41.359Z]  [OUT] initiate restore
[2024-06-22T18:40:41.359Z]  [OUT] Removed test output files
[2024-06-22T18:40:41.359Z]  [OUT] finished script
[2024-06-22T18:40:41.359Z]  [ERR] /home/jenkins/workspace/Test_openjdk22_j9_sanity.functional_aarch64_linux_testList_0/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh: line 41: 17655 Killed                  $2 -XX:+EnableCRIUSupport $3 -cp "$1/criu.jar" $4 $5 $6 > testOutput 2>&1
[2024-06-22T18:40:41.359Z] >> Success condition was not found: [Output match: User requested Java dump using]
[2024-06-22T18:40:41.359Z] >> Success condition was not found: [Output match: TEST PASSED]
[2024-06-22T18:40:41.359Z] >> Required condition was found: [Output match: Pre-checkpoint]
[2024-06-22T18:40:41.359Z] >> Failure condition was found: [Output match: Killed]
[2024-06-22T18:40:41.359Z] >> Failure condition was not found: [Output match: CRIU is not enabled]
[2024-06-22T18:40:41.359Z] >> Failure condition was not found: [Output match: Operation not permitted]
[2024-06-22T18:40:41.359Z] >> Success condition was not found: [Output match: Thread pid mismatch]
[2024-06-22T18:40:41.359Z] >> Success condition was not found: [Output match: do not match expected]
[2024-06-22T18:40:41.359Z] >> Success condition was not found: [Output match: Unable to create a thread:]
[2024-06-22T18:40:41.359Z] >> Failure condition was found: [Output match: TEST FAILED]
[2024-06-22T18:40:41.360Z] >> Failure condition was not found: [Output match: Could not dump the JVM processes, err=-70]

[2024-06-22T19:37:17.633Z] cmdLineTester_criu_nonPortableRestore_10_FAILED

15x grinder - passed