-
Bug
-
Resolution: Duplicate
-
P4
-
None
-
17
-
x86_64
-
linux
The following test failed in the JDK17 CI:
gtest/AsyncLogGtest.java
Here's a snippet from the log file:
----------System.out:(53/3924)----------
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+28-2528/linux-x64-debug.test/hotspot/gtest/server/gtestLauncher -jdk /opt/mach5/mesos/work_dir/jib-master/install/jdk-17+28-2528/linux-x64-debug.jdk/jdk-17/fastdebug --gtest_output=xml:test_result.xml --gtest_catch_exceptions=0test_result.xml --gtest_filter=Log*Test* -Xlog:async]
[2021-06-23T09:22:31.982273745Z] Gathering output for process 1668
[2021-06-23T09:22:41.044959272Z] Waiting for completion for process 1668
[2021-06-23T09:22:41.045155683Z] Waiting for completion finished for process 1668
Output and diagnostic info for process 1668 was saved into 'pid-1668-output.log'
Note: Google Test filter = Log*Test*
[==========] Running 38 tests from 4 test cases.
[----------] Global test environment set-up.
[----------] 8 tests from LogTest
[ RUN ] LogTest.prefix_vm
[ OK ] LogTest.prefix_vm (1 ms)
[ RUN ] LogTest.large_message_vm
[ OK ] LogTest.large_message_vm (0 ms)
[ RUN ] LogTest.enabled_logtarget_vm
[ OK ] LogTest.enabled_logtarget_vm (0 ms)
[ RUN ] LogTest.disabled_logtarget_vm
[ OK ] LogTest.disabled_logtarget_vm (0 ms)
[ RUN ] LogTest.enabled_loghandle_vm
[ OK ] LogTest.enabled_loghandle_vm (1 ms)
[ RUN ] LogTest.disabled_loghandle_vm
[ OK ] LogTest.disabled_loghandle_vm (0 ms)
[ RUN ] LogTest.enabled_logtargethandle_vm
[ OK ] LogTest.enabled_logtargethandle_vm (0 ms)
[ RUN ] LogTest.disabled_logtargethandle_vm
[ OK ] LogTest.disabled_logtargethandle_vm (1 ms)
[----------] 8 tests from LogTest (172 ms total)
[----------] 15 tests from LogConfigurationTest
[ RUN ] LogConfigurationTest.describe_vm
# To suppress the following error report, specify this argument
# after -XX: or in .hotspotrc: SuppressErrorAt=/logDecorations.cpp:78
#
# A fatal error has been detected by the Java Runtime Environment:
#
# Internal Error (/opt/mach5/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S79382/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/c37d2edc-4255-4124-8502-b80735762f72/runs/07669906-43eb-4126-a0ff-c606a1911bbc/workspace/open/src/hotspot/share/logging/logDecorations.cpp:78), pid=1668, tid=1674
# assert(_decorators.is_decorator(decorator)) failed: decorator was not part of the decorator set specified at creation.
#
# JRE version: Java(TM) SE Runtime Environment (17.0+28) (fastdebug build 17-ea+28-LTS-2528)
# Java VM: Java HotSpot(TM) 64-Bit Server VM (fastdebug 17-ea+28-LTS-2528, mixed mode, tiered, compressed oops, compressed class ptrs, g1 gc, linux-amd64)
# Problematic frame:
# V [libjvm.so+0x15feb54] LogDecorations::decoration(LogDecorators::Decorator, char*, unsigned long) const+0x94
#
# Core dump will be written. Default location: Core dumps may be processed with "/opt/core.sh %p" (or dumping to /opt/mach5/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S57023/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2ff7ffb6-1707-4d45-9528-113536e6517a/runs/0f0617da-bedb-4636-9b0f-00c0e42fd89c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_misc/scratch/1/core.1668)
#
# An error report file with more information is saved as:
# /opt/mach5/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S57023/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2ff7ffb6-1707-4d45-9528-113536e6517a/runs/0f0617da-bedb-4636-9b0f-00c0e42fd89c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_misc/scratch/1/hs_err_pid1668.log
#
# If you would like to submit a bug report, please visit:
# https://bugreport.java.com/bugreport/crash.jsp
#
assert failed: assert(_decorators.is_decorator(decorator)) failed: decorator was not part of the decorator set specified at creation.
[2021-06-23T09:22:41.096726803Z] Waiting for completion for process 1668
[2021-06-23T09:22:41.096848934Z] Waiting for completion finished for process 1668
----------System.err:(12/722)----------
java.lang.AssertionError: gtest execution failed; exit code = 134.
at GTestWrapper.main(GTestWrapper.java:98)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
at java.base/java.lang.Thread.run(Thread.java:833)
JavaTest Message: Test threw exception: java.lang.AssertionError
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.AssertionError: gtest execution failed; exit code = 134.
Here's the crashing thread's stack:
--------------- T H R E A D ---------------
Current thread (0x0000560c2bfd8a80): Thread "AsyncLog Thread" [stack: 0x00007f79dbe24000,0x00007f79dbf24000] [id=1674]
Stack: [0x00007f79dbe24000,0x00007f79dbf24000], sp=0x00007f79dbf225f0, free space=1017k
Native frames: (J=compiled Java code, j=interpreted, Vv=VM code, C=native code)
V [libjvm.so+0x15feb54] LogDecorations::decoration(LogDecorators::Decorator, char*, unsigned long) const+0x94
V [libjvm.so+0x1601977] LogFileStreamOutput::write(LogDecorations const&, char const*)+0x77
V [libjvm.so+0x1600c62] LogFileOutput::write_blocking(LogDecorations const&, char const*)+0x42
V [libjvm.so+0x15fb1c5] AsyncLogWriter::run()+0x395
V [libjvm.so+0x1c122c0] Thread::call_run()+0x100
V [libjvm.so+0x18df194] thread_native_entry(Thread*)+0x104
[error occurred during error reporting (printing target Java thread stack), id 0xb, SIGSEGV (0xb) at pc=0x00000000801f0fc3]
This failure mode was previously fixed via:
JDK-8267926 AsyncLogGtest.java fails on assert with: decorator was not part of the decorator set specified at creation.
gtest/AsyncLogGtest.java
Here's a snippet from the log file:
----------System.out:(53/3924)----------
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-17+28-2528/linux-x64-debug.test/hotspot/gtest/server/gtestLauncher -jdk /opt/mach5/mesos/work_dir/jib-master/install/jdk-17+28-2528/linux-x64-debug.jdk/jdk-17/fastdebug --gtest_output=xml:test_result.xml --gtest_catch_exceptions=0test_result.xml --gtest_filter=Log*Test* -Xlog:async]
[2021-06-23T09:22:31.982273745Z] Gathering output for process 1668
[2021-06-23T09:22:41.044959272Z] Waiting for completion for process 1668
[2021-06-23T09:22:41.045155683Z] Waiting for completion finished for process 1668
Output and diagnostic info for process 1668 was saved into 'pid-1668-output.log'
Note: Google Test filter = Log*Test*
[==========] Running 38 tests from 4 test cases.
[----------] Global test environment set-up.
[----------] 8 tests from LogTest
[ RUN ] LogTest.prefix_vm
[ OK ] LogTest.prefix_vm (1 ms)
[ RUN ] LogTest.large_message_vm
[ OK ] LogTest.large_message_vm (0 ms)
[ RUN ] LogTest.enabled_logtarget_vm
[ OK ] LogTest.enabled_logtarget_vm (0 ms)
[ RUN ] LogTest.disabled_logtarget_vm
[ OK ] LogTest.disabled_logtarget_vm (0 ms)
[ RUN ] LogTest.enabled_loghandle_vm
[ OK ] LogTest.enabled_loghandle_vm (1 ms)
[ RUN ] LogTest.disabled_loghandle_vm
[ OK ] LogTest.disabled_loghandle_vm (0 ms)
[ RUN ] LogTest.enabled_logtargethandle_vm
[ OK ] LogTest.enabled_logtargethandle_vm (0 ms)
[ RUN ] LogTest.disabled_logtargethandle_vm
[ OK ] LogTest.disabled_logtargethandle_vm (1 ms)
[----------] 8 tests from LogTest (172 ms total)
[----------] 15 tests from LogConfigurationTest
[ RUN ] LogConfigurationTest.describe_vm
# To suppress the following error report, specify this argument
# after -XX: or in .hotspotrc: SuppressErrorAt=/logDecorations.cpp:78
#
# A fatal error has been detected by the Java Runtime Environment:
#
# Internal Error (/opt/mach5/mesos/work_dir/slaves/3c846bae-ce30-4a97-93ee-9fef4497ccb6-S79382/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/c37d2edc-4255-4124-8502-b80735762f72/runs/07669906-43eb-4126-a0ff-c606a1911bbc/workspace/open/src/hotspot/share/logging/logDecorations.cpp:78), pid=1668, tid=1674
# assert(_decorators.is_decorator(decorator)) failed: decorator was not part of the decorator set specified at creation.
#
# JRE version: Java(TM) SE Runtime Environment (17.0+28) (fastdebug build 17-ea+28-LTS-2528)
# Java VM: Java HotSpot(TM) 64-Bit Server VM (fastdebug 17-ea+28-LTS-2528, mixed mode, tiered, compressed oops, compressed class ptrs, g1 gc, linux-amd64)
# Problematic frame:
# V [libjvm.so+0x15feb54] LogDecorations::decoration(LogDecorators::Decorator, char*, unsigned long) const+0x94
#
# Core dump will be written. Default location: Core dumps may be processed with "/opt/core.sh %p" (or dumping to /opt/mach5/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S57023/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2ff7ffb6-1707-4d45-9528-113536e6517a/runs/0f0617da-bedb-4636-9b0f-00c0e42fd89c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_misc/scratch/1/core.1668)
#
# An error report file with more information is saved as:
# /opt/mach5/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S57023/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/2ff7ffb6-1707-4d45-9528-113536e6517a/runs/0f0617da-bedb-4636-9b0f-00c0e42fd89c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_misc/scratch/1/hs_err_pid1668.log
#
# If you would like to submit a bug report, please visit:
# https://bugreport.java.com/bugreport/crash.jsp
#
assert failed: assert(_decorators.is_decorator(decorator)) failed: decorator was not part of the decorator set specified at creation.
[2021-06-23T09:22:41.096726803Z] Waiting for completion for process 1668
[2021-06-23T09:22:41.096848934Z] Waiting for completion finished for process 1668
----------System.err:(12/722)----------
java.lang.AssertionError: gtest execution failed; exit code = 134.
at GTestWrapper.main(GTestWrapper.java:98)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
at java.base/java.lang.Thread.run(Thread.java:833)
JavaTest Message: Test threw exception: java.lang.AssertionError
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.AssertionError: gtest execution failed; exit code = 134.
Here's the crashing thread's stack:
--------------- T H R E A D ---------------
Current thread (0x0000560c2bfd8a80): Thread "AsyncLog Thread" [stack: 0x00007f79dbe24000,0x00007f79dbf24000] [id=1674]
Stack: [0x00007f79dbe24000,0x00007f79dbf24000], sp=0x00007f79dbf225f0, free space=1017k
Native frames: (J=compiled Java code, j=interpreted, Vv=VM code, C=native code)
V [libjvm.so+0x15feb54] LogDecorations::decoration(LogDecorators::Decorator, char*, unsigned long) const+0x94
V [libjvm.so+0x1601977] LogFileStreamOutput::write(LogDecorations const&, char const*)+0x77
V [libjvm.so+0x1600c62] LogFileOutput::write_blocking(LogDecorations const&, char const*)+0x42
V [libjvm.so+0x15fb1c5] AsyncLogWriter::run()+0x395
V [libjvm.so+0x1c122c0] Thread::call_run()+0x100
V [libjvm.so+0x18df194] thread_native_entry(Thread*)+0x104
[error occurred during error reporting (printing target Java thread stack), id 0xb, SIGSEGV (0xb) at pc=0x00000000801f0fc3]
This failure mode was previously fixed via:
- duplicates
-
JDK-8267952 async logging supports to dynamically change tags and decorators
-
- Resolved
-
- relates to
-
JDK-8267926 AsyncLogGtest.java fails on assert with: decorator was not part of the decorator set specified at creation.
-
- Resolved
-