cmdLineTester_criu_nonPortableRestore_4 Error (criu/cr-restore.c:1487): Can't fork for 2223193: File exists, Restoring FAILED

[JasonFengJ9]

Failure link

From internal Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3 (rtj-ubu22s390x-svl-test-1yht1k-1)

openjdk version "25.0.1" 2025-10-21 LTS
IBM Semeru Runtime Open Edition 25.0.2.0-m1 (build 25.0.1+8-LTS)
Eclipse OpenJ9 VM 25.0.2.0-m1 (build 25.0.1+8-v0.57.0-release-4260f033c7, JRE 25 Linux s390x-64-Bit Compressed References 20251021_95 (JIT enabled, AOT enabled)
OpenJ9   - 4260f033c7
OMR      - 10d2ee163
JCL      - e55b60a1f53 based on jdk-25.0.1+8)

Rerun in Grinder - Change TARGET to run only the failed test targets

Optional info

Failure output (captured from console output)

[2025-11-21T14:45:58.157Z] Running test cmdLineTester_criu_nonPortableRestore_4 ...
[2025-11-21T14:45:58.157Z] ===============================================
[2025-11-21T14:45:58.157Z] cmdLineTester_criu_nonPortableRestore_4 Start Time: Fri Nov 21 14:45:55 2025 Epoch Time (ms): 1763736355119
[2025-11-21T14:45:58.157Z] variation: -Xjit:count=0 -XX:+CRIURestoreNonPortableMode
[2025-11-21T14:45:58.157Z] JVM_OPTIONS:  -Xjit:count=0 -XX:+CRIURestoreNonPortableMode 

[2025-11-21T15:06:13.436Z] Testing: Create Criu Checkpoint Image once and no restore - TestConcurrentModePreCheckpointHookPriorities
[2025-11-21T15:06:13.436Z] Test start time: 2025/11/21 15:06:11 Coordinated Universal Time
[2025-11-21T15:06:13.437Z] Running command: bash /home/jenkins/workspace/Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh /home/jenkins/workspace/Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu /home/jenkins/workspace/Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3/jdkbinary/j2sdk-image/bin/java " -Xjit:count=0 -XX:+CRIURestoreNonPortableMode " org.openj9.criu.TestConcurrentMode TestConcurrentModePreCheckpointHookPriorities 1 false false
[2025-11-21T15:06:13.437Z] Time spent starting: 116 milliseconds
[2025-11-21T15:06:39.933Z] Time spent executing: 26855 milliseconds
[2025-11-21T15:06:39.933Z] Test result: FAILED
[2025-11-21T15:06:39.933Z] Output from test:
[2025-11-21T15:06:39.933Z]  [OUT] start running script
[2025-11-21T15:06:39.933Z]  [OUT] export GLIBC_TUNABLES=glibc.cpu.hwcaps=-XSAVEC,-XSAVE,-AVX2,-ERMS,-AVX,-AVX_Fast_Unaligned_Load
[2025-11-21T15:06:39.933Z]  [OUT] export LD_BIND_NOT=on
[2025-11-21T15:06:39.935Z]  [OUT] /home/jenkins/workspace/Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3/jdkbinary/j2sdk-image/bin/java -XX:+EnableCRIUSupport  -Xjit:count=0 -XX:+CRIURestoreNonPortableMode  -cp /home/jenkins/workspace/Test_openjdk25_j9_sanity.functional_s390x_linux_testList_3/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criu.jar org.openj9.criu.TestConcurrentMode TestConcurrentModePreCheckpointHookPriorities 1
[2025-11-21T15:06:39.935Z]  [OUT] main: Fri Nov 21 15:06:26 UTC 2025, TestConcurrentModePreCheckpointHookPriorities() starts .., System.currentTimeMillis(): 1763737591598, System.nanoTime(): 1763737492663850074
[2025-11-21T15:06:39.936Z]  [OUT] main: Fri Nov 21 15:06:33 UTC 2025, TestConcurrentModePreCheckpointHookPriorities() Pre-checkpoint, System.currentTimeMillis(): 1763737593921, System.nanoTime(): 1763737494986713707
[2025-11-21T15:06:39.936Z]  [OUT] main: Fri Nov 21 15:06:33 UTC 2025, Performing CRIUSupport.checkpointJVM(), System.currentTimeMillis(): 1763737593927, System.nanoTime(): 1763737494992323126
[2025-11-21T15:06:39.936Z]  [OUT] main: Fri Nov 21 15:06:34 UTC 2025, The preCheckpointHook with lower priority in CONCURRENT_MODE, System.currentTimeMillis(): 1763737594585, System.nanoTime(): 1763737495650042658
[2025-11-21T15:06:39.936Z]  [OUT] main: Fri Nov 21 15:06:34 UTC 2025, The preCheckpointHook with higher priority in CONCURRENT_MODE, System.currentTimeMillis(): 1763737594593, System.nanoTime(): 1763737495658129540
[2025-11-21T15:06:39.937Z]  [OUT] main: Fri Nov 21 15:06:35 UTC 2025, The preCheckpointHook with lower priority in SINGLE_THREAD_MODE, System.currentTimeMillis(): 1763737595061, System.nanoTime(): 1763737496126410088
[2025-11-21T15:06:39.937Z]  [OUT] main: Fri Nov 21 15:06:35 UTC 2025, The preCheckpointHook with higher priority in SINGLE_THREAD_MODE, System.currentTimeMillis(): 1763737595093, System.nanoTime(): 1763737496158302940
[2025-11-21T15:06:39.937Z]  [OUT] initiate restore
[2025-11-21T15:06:39.937Z]  [OUT] 2222489: Error (criu/cr-restore.c:1487): Can't fork for 2223193: File exists
[2025-11-21T15:06:39.937Z]  [OUT] Error (criu/cr-restore.c:2557): Restoring FAILED.

50x internal Grinder - the failure wasn't reproduced

[JasonFengJ9]

openjdk11_j9_sanity.functional_s390x_linux

[2025-11-24T14:36:17.022Z] Running test cmdLineTester_criu_nonPortableRestore_0 ...
[2025-11-24T14:36:17.022Z] ===============================================
[2025-11-24T14:36:17.023Z] cmdLineTester_criu_nonPortableRestore_0 Start Time: Mon Nov 24 06:36:14 2025 Epoch Time (ms): 1763994974423
[2025-11-24T14:36:17.023Z] variation: -Xjit -XX:+CRIURestoreNonPortableMode
[2025-11-24T14:36:17.023Z] JVM_OPTIONS:  -Xjit -XX:+CRIURestoreNonPortableMode 

[2025-11-24T14:37:27.202Z] Testing: Create and Restore Criu Checkpoint Image once - MethodTypeDeadlockTest
[2025-11-24T14:37:27.202Z] Test start time: 2025/11/24 06:37:27 Pacific Standard Time
[2025-11-24T14:37:27.204Z] Running command: bash /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_s390x_linux_testList_5/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criuScript.sh /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_s390x_linux_testList_5/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_s390x_linux_testList_5/jdkbinary/j2sdk-image/bin/java " -Xjit -XX:+CRIURestoreNonPortableMode  -XX:+ThrowOnDelayedCheckpointOperation -XX:sleepMillisecondsForNotCheckpointSafe=20 -Xtrace:print=j9vm.731 --add-opens java.base/jdk.internal.misc=ALL-UNNAMED  --add-exports java.base/openj9.internal.criu=ALL-UNNAMED" org.openj9.criu.DeadlockTest MethodTypeDeadlockTest 1 false false
[2025-11-24T14:37:27.204Z] Time spent starting: 1 milliseconds
[2025-11-24T14:37:30.809Z] Time spent executing: 2988 milliseconds
[2025-11-24T14:37:30.809Z] Test result: FAILED
[2025-11-24T14:37:30.809Z] Output from test:
[2025-11-24T14:37:30.809Z]  [OUT] start running script
[2025-11-24T14:37:30.809Z]  [OUT] export GLIBC_TUNABLES=glibc.cpu.hwcaps=-XSAVEC,-XSAVE,-AVX2,-ERMS,-AVX,-AVX_Fast_Unaligned_Load
[2025-11-24T14:37:30.809Z]  [OUT] export LD_BIND_NOT=on
[2025-11-24T14:37:30.810Z]  [OUT] /home/jenkins/workspace/Test_openjdk11_j9_sanity.functional_s390x_linux_testList_5/jdkbinary/j2sdk-image/bin/java -XX:+EnableCRIUSupport  -Xjit -XX:+CRIURestoreNonPortableMode  -XX:+ThrowOnDelayedCheckpointOperation -XX:sleepMillisecondsForNotCheckpointSafe=20 -Xtrace:print=j9vm.731 --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_s390x_linux_testList_5/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/criu/criu.jar org.openj9.criu.DeadlockTest MethodTypeDeadlockTest 1
[2025-11-24T14:37:30.810Z]  [OUT] Pre-checkpoint
[2025-11-24T14:37:30.810Z]  [OUT] main: Mon Nov 24 06:37:27 PST 2025, Performing CRIUSupport.checkpointJVM(), System.currentTimeMillis(): 1763995047470, System.nanoTime(): 1763994943425015859
[2025-11-24T14:37:30.810Z]  [OUT] JVMJITM044W Some or all compiled code in the code cache invalidated post restore.
[2025-11-24T14:37:30.810Z]  [OUT] initiate restore
[2025-11-24T14:37:30.810Z]  [OUT] Error (criu/protobuf.c:72): Unexpected EOF on (empty-image)
[2025-11-24T14:37:30.810Z]  [OUT] Error (criu/arch/s390/crtools.c:776): Cannot open core for virt pid 3091176: No such file or directory
[2025-11-24T14:37:30.810Z]  [OUT] Error (criu/cr-restore.c:2152): Restoring regs for 3091176 failed: No such file or directory
[2025-11-24T14:37:30.810Z]  [OUT] Error (criu/cr-restore.c:2530): Killing processes because of failure on restore.
[2025-11-24T14:37:30.810Z]  [OUT] The Network was unlocked so some data or a connection may have been lost.
[2025-11-24T14:37:30.810Z]  [OUT] Error (criu/cr-restore.c:2557): Restoring FAILED.