JDK-8246352 : TestMetaspacePerfCounters.java#id1 assert(gc_data(nm).not_marked()) failed
  • Type: Bug
  • Component: hotspot
  • Sub-Component: gc
  • Affected Version: 15
  • Priority: P2
  • Status: Resolved
  • Resolution: Cannot Reproduce
  • OS: linux
  • CPU: x86_64
  • Submitted: 2020-06-02
  • Updated: 2020-06-11
  • Resolved: 2020-06-11
Related Reports
Relates :  
Description
The following test failed in the JDK15 CI:

gc/metaspace/TestMetaspacePerfCounters.java#id1

Here's a snippet from the log file:

#section:main
----------messages:(5/742)----------
command: main -XX:+IgnoreUnrecognizedVMOptions -XX:-UseCompressedOops -XX:-UseCompressedClassPointers -XX:+UsePerfData -XX:+UseParallelGC gc.metaspace.TestMetaspacePerfCounters
reason: User specified action: run main/othervm -XX:+IgnoreUnrecognizedVMOptions -XX:-UseCompressedOops -XX:-UseCompressedClassPointers -XX:+UsePerfData -XX:+UseParallelGC gc.metaspace.TestMetaspacePerfCounters 
Mode: othervm [/othervm specified]
Additional options from @modules: --add-modules java.base,java.compiler,java.management,jdk.internal.jvmstat --add-exports java.base/jdk.internal.misc=ALL-UNNAMED --add-exports java.management/sun.management=ALL-UNNAMED --add-exports jdk.internal.jvmstat/sun.jvmstat.monitor=ALL-UNNAMED
elapsed time (seconds): 62.131
----------configuration:(6/293)----------
Boot Layer
  add modules: java.base java.compiler java.management jdk.internal.jvmstat 
  add exports: java.base/jdk.internal.misc              ALL-UNNAMED
               java.management/sun.management           ALL-UNNAMED
               jdk.internal.jvmstat/sun.jvmstat.monitor ALL-UNNAMED

----------System.out:(68/4868)----------
# To suppress the following error report, specify this argument
# after -XX: or in .hotspotrc:  SuppressErrorAt=/scavengableNMethods.cpp:116
#
# A fatal error has been detected by the Java Runtime Environment:
#
#  Internal Error (/opt/mach5/mesos/work_dir/slaves/805146e6-8fdb-4552-bf9e-385b73cf7129-S322/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5d53a0b5-901b-4d99-bd32-05e5a65da3fe/runs/2f2736c3-d92b-45e1-9e2c-870914e02f59/workspace/open/src/hotspot/share/gc/shared/scavengableNMethods.cpp:116), pid=13150, tid=13248
#  Error: assert(gc_data(nm).not_marked()) failed
#
# JRE version: Java(TM) SE Runtime Environment (15.0+26) (fastdebug build 15-ea+26-1262)
# Java VM: Java HotSpot(TM) 64-Bit Server VM (fastdebug 15-ea+26-1262, compiled mode, parallel gc, linux-amd64)
# Problematic frame:
# V  [libjvm.so+0x14f907c]  ScavengableNMethods::verify_nmethod(nmethod*)+0xdc
#
# 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/805146e6-8fdb-4552-bf9e-385b73cf7129-S259/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/dab43363-3151-4c34-8c1d-cc961bdb2f68/runs/89566b5c-0245-495a-b117-739bec454c46/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_gc/scratch/2/core.13150)
#
# An error report file with more information is saved as:
# /opt/mach5/mesos/work_dir/slaves/805146e6-8fdb-4552-bf9e-385b73cf7129-S259/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/dab43363-3151-4c34-8c1d-cc961bdb2f68/runs/89566b5c-0245-495a-b117-739bec454c46/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_gc/scratch/2/hs_err_pid13150.log
Compiled method (c2)   46841 5740             com.sun.tools.javac.comp.TypeEnter::instance (26 bytes)
 total in heap  [0x00007f3dd9685490,0x00007f3dd96884a8] = 12312
 relocation     [0x00007f3dd9685608,0x00007f3dd9685810] = 520
 main code      [0x00007f3dd9685820,0x00007f3dd9686960] = 4416
 stub code      [0x00007f3dd9686960,0x00007f3dd9686b00] = 416
 oops           [0x00007f3dd9686b00,0x00007f3dd9686b58] = 88
 metadata       [0x00007f3dd9686b58,0x00007f3dd9686c30] = 216
 scopes data    [0x00007f3dd9686c30,0x00007f3dd9687368] = 1848
 scopes pcs     [0x00007f3dd9687368,0x00007f3dd96880c8] = 3424
 dependencies   [0x00007f3dd96880c8,0x00007f3dd96880d8] = 16
 handler table  [0x00007f3dd96880d8,0x00007f3dd9688498] = 960
 nul chk table  [0x00007f3dd9688498,0x00007f3dd96884a8] = 16
Compiled method (c2)   46841 4407             javax.tools.JavaFileObject$Kind::$values (29 bytes)
 total in heap  [0x00007f3dd955b410,0x00007f3dd955b8a8] = 1176
 relocation     [0x00007f3dd955b588,0x00007f3dd955b5b0] = 40
 main code      [0x00007f3dd955b5c0,0x00007f3dd955b720] = 352
 stub code      [0x00007f3dd955b720,0x00007f3dd955b738] = 24
 oops           [0x00007f3dd955b738,0x00007f3dd955b740] = 8
 metadata       [0x00007f3dd955b740,0x00007f3dd955b748] = 8
 scopes data    [0x00007f3dd955b748,0x00007f3dd955b788] = 64
 scopes pcs     [0x00007f3dd955b788,0x00007f3dd955b888] = 256
 dependencies   [0x00007f3dd955b888,0x00007f3dd955b890] = 8
 handler table  [0x00007f3dd955b890,0x00007f3dd955b8a8] = 24
Compiled method (c2)   46844 5740             com.sun.tools.javac.comp.TypeEnter::instance (26 bytes)
 total in heap  [0x00007f3dd9685490,0x00007f3dd96884a8] = 12312
 relocation     [0x00007f3dd9685608,0x00007f3dd9685810] = 520
 main code      [0x00007f3dd9685820,0x00007f3dd9686960] = 4416
 stub code      [0x00007f3dd9686960,0x00007f3dd9686b00] = 416
 oops           [0x00007f3dd9686b00,0x00007f3dd9686b58] = 88
 metadata       [0x00007f3dd9686b58,0x00007f3dd9686c30] = 216
 scopes data    [0x00007f3dd9686c30,0x00007f3dd9687368] = 1848
 scopes pcs     [0x00007f3dd9687368,0x00007f3dd96880c8] = 3424
 dependencies   [0x00007f3dd96880c8,0x00007f3dd96880d8] = 16
 handler table  [0x00007f3dd96880d8,0x00007f3dd9688498] = 960
 nul chk table  [0x00007f3dd9688498,0x00007f3dd96884a8] = 16
Compiled method (c2)   46844 5740             com.sun.tools.javac.comp.TypeEnter::instance (26 bytes)
 total in heap  [0x00007f3dd9685490,0x00007f3dd96884a8] = 12312
 relocation     [0x00007f3dd9685608,0x00007f3dd9685810] = 520
 main code      [0x00007f3dd9685820,0x00007f3dd9686960] = 4416
 stub code      [0x00007f3dd9686960,0x00007f3dd9686b00] = 416
 oops           [0x00007f3dd9686b00,0x00007f3dd9686b58] = 88
 metadata       [0x00007f3dd9686b58,0x00007f3dd9686c30] = 216
 scopes data    [0x00007f3dd9686c30,0x00007f3dd9687368] = 1848
 scopes pcs     [0x00007f3dd9687368,0x00007f3dd96880c8] = 3424
 dependencies   [0x00007f3dd96880c8,0x00007f3dd96880d8] = 16
 handler table  [0x00007f3dd96880d8,0x00007f3dd9688498] = 960
 nul chk table  [0x00007f3dd9688498,0x00007f3dd96884a8] = 16
#
# If you would like to submit a bug report, please visit:
#   https://bugreport.java.com/bugreport/crash.jsp
#
----------System.err:(0/0)----------
----------rerun:(51/7874)*----------


Here's the crashing thread's stack:

---------------  T H R E A D  ---------------

Current thread (0x00007f3ce4001de0):  GCTaskThread "GC Thread#2" [stack: 0x00007f3ce91cb000,0x00007f3ce92cb000] [id=13248]

Stack: [0x00007f3ce91cb000,0x00007f3ce92cb000],  sp=0x00007f3ce92c9c30,  free space=1019k
Native frames: (J=compiled Java code, A=aot compiled Java code, j=interpreted, Vv=VM code, C=native code)
V  [libjvm.so+0x14f907c]  ScavengableNMethods::verify_nmethod(nmethod*)+0xdc
V  [libjvm.so+0x14f9b32]  ScavengableNMethods::nmethods_do_and_prune(CodeBlobToOopClosure*)+0x382
V  [libjvm.so+0x147f037]  ScavengeRootsTask::work(unsigned int)+0x457
V  [libjvm.so+0x17dcc34]  GangWorker::run_task(WorkData)+0x84
V  [libjvm.so+0x17dcd5e]  GangWorker::loop()+0x2e
V  [libjvm.so+0x16a0280]  Thread::call_run()+0x100
V  [libjvm.so+0x13a5456]  thread_native_entry(Thread*)+0x116
Comments
Have not seen any complaints about my plan to close this bug as "Cannot Reproduce" so I'm closing it.
11-06-2020