cmdLineTester_vmRuntimeState_0 ERROR: Expected J9HOOK_VM_RUNTIME_STATE_CHANGED to be triggered #22610
Description
Failure link
From internal Test_openjdk21_j9_extended.functional_x86-64_mac_testList_0 (osxrt6)
java version "21.0.9-beta" 2025-10-21
IBM Semeru Runtime Certified Edition 21.0.9+6-202509110240 (build 21.0.9-beta+6-202509110240)
Eclipse OpenJ9 VM 21.0.9+6-202509110240 (build master-bbd11673d4, JRE 21 Mac OS X amd64-64-Bit Compressed References 20250911_543 (JIT enabled, AOT enabled)
OpenJ9 - bbd11673d4
OMR - 880476700
JCL - c5b4cf4c6 based on jdk-21.0.9+6)
Rerun in Grinder - Change TARGET to run only the failed test targets
Optional info
Failure output (captured from console output)
[2025-09-11T06:32:53.463Z] Running test cmdLineTester_vmRuntimeState_0 ...
[2025-09-11T06:32:53.463Z] ===============================================
[2025-09-11T06:32:53.463Z] cmdLineTester_vmRuntimeState_0 Start Time: Thu Sep 11 02:32:53 2025 Epoch Time (ms): 1757572373283
[2025-09-11T06:32:53.463Z] variation: NoOptions
[2025-09-11T06:32:53.463Z] JVM_OPTIONS:
[2025-09-11T06:32:54.559Z] Time spent starting: 105 milliseconds
[2025-09-11T06:36:05.111Z] Time spent executing: 185523 milliseconds
[2025-09-11T06:36:05.111Z] Test result: FAILED
[2025-09-11T06:36:05.111Z] Output from test:
[2025-09-11T06:36:05.111Z] [OUT] Busy looping ...
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 72
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 103
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 91
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 94
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 90
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 93
[2025-09-11T06:36:05.111Z] [OUT] Busy loop done
[2025-09-11T06:36:05.111Z] [OUT] Idling ...
[2025-09-11T06:36:05.111Z] [OUT] Idling done
[2025-09-11T06:36:05.111Z] [OUT] Busy looping ...
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 72
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 74
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 105
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 71
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 68
[2025-09-11T06:36:05.111Z] [OUT] Prime numbers in the list: 69
[2025-09-11T06:36:05.111Z] [OUT] Busy loop done
[2025-09-11T06:36:05.111Z] [ERR] ERROR: Expected J9HOOK_VM_RUNTIME_STATE_CHANGED to be triggered
[2025-09-11T06:36:05.111Z] >> Success condition was not found: [Output match: All Tests Completed and Passed]
[2025-09-11T06:36:05.111Z] >> Required condition was found: [Output match: Busy looping]
[2025-09-11T06:36:05.111Z] >> Required condition was found: [Output match: Idling]
[2025-09-11T06:36:05.111Z] >> Required condition was not found: [Output match: PASS]
[2025-09-11T06:36:05.111Z] >> Failure condition was found: [Output match: ERROR]
[2025-09-11T06:36:05.111Z] >> Failure condition was not found: [Output match: Unhandled Exception]
[2025-09-11T06:36:05.111Z] >> Failure condition was not found: [Output match: Exception:]
[2025-09-11T06:36:05.111Z] >> Failure condition was not found: [Output match: corrupt]
[2025-09-11T06:36:05.111Z] >> Failure condition was not found: [Output match: Processing dump event]
[2025-09-11T06:49:30.895Z] Testing: Test J9HOOK_VM_RUNTIME_STATE_CHANGED event is triggererd when sampling frequency in deep idle is 0
[2025-09-11T06:49:30.895Z] Test start time: 2025/09/11 02:49:27 Eastern Standard Time
[2025-09-11T06:49:30.895Z] Running command: "/Users/jenkins/workspace/Test_openjdk21_j9_extended.functional_x86-64_mac_testList_0/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java" -Xdump -Xjit:samplingFrequencyInDeepIdleMode=0 -XX:IdleTuningMinIdleWaitTime=60 -cp /Users/jenkins/workspace/Test_openjdk21_j9_extended.functional_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/functional/cmdLineTests/vmRuntimeState/vmruntimestate.jar -agentlib:vmruntimestateagent29=appClass:ActiveIdleTest,triggerHook:yes ActiveIdleTest --busy-period=30 --idle-period=120
[2025-09-11T06:49:30.895Z] Time spent starting: 4 milliseconds
[2025-09-11T06:52:41.098Z] Time spent executing: 183634 milliseconds
[2025-09-11T06:52:41.098Z] Test result: FAILED
[2025-09-11T06:52:41.098Z] Output from test:
[2025-09-11T06:52:41.098Z] [OUT] Busy looping ...
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 74
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 67
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 76
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 66
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 78
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 68
[2025-09-11T06:52:41.098Z] [OUT] Busy loop done
[2025-09-11T06:52:41.098Z] [OUT] Idling ...
[2025-09-11T06:52:41.098Z] [OUT] Idling done
[2025-09-11T06:52:41.098Z] [OUT] Busy looping ...
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 77
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 91
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 62
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 83
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 80
[2025-09-11T06:52:41.098Z] [OUT] Prime numbers in the list: 74
[2025-09-11T06:52:41.098Z] [OUT] Busy loop done
[2025-09-11T06:52:41.098Z] [ERR] ERROR: Expected J9HOOK_VM_RUNTIME_STATE_CHANGED to be triggered
[2025-09-11T06:52:41.098Z] >> Success condition was not found: [Output match: All Tests Completed and Passed]
[2025-09-11T06:52:41.098Z] >> Required condition was found: [Output match: Busy looping]
[2025-09-11T06:52:41.098Z] >> Required condition was found: [Output match: Idling]
[2025-09-11T06:52:41.098Z] >> Required condition was not found: [Output match: PASS]
[2025-09-11T06:52:41.098Z] >> Failure condition was found: [Output match: ERROR]
[2025-09-11T06:52:41.098Z] >> Failure condition was not found: [Output match: INFO]
[2025-09-11T06:52:41.098Z] >> Failure condition was not found: [Output match: Exception:]
[2025-09-11T06:52:41.098Z] >> Failure condition was not found: [Output match: corrupt]
[2025-09-11T06:52:41.098Z] >> Failure condition was not found: [Output match: Processing dump event]
50x internal Grinder - failed 1/10 on macx64rt7, 1/10 on osxrt4.