[2025-05-22T03:47:25.189+0000][90209][gc ] Trigger: Time since last GC (300002 ms) is larger than guaranteed interval (300000 ms)
[2025-05-22T03:47:25.192+0000][90209][gc,ergo ] Free: 333M, Max: 256K regular, 86016K humongous, Frag: 72% external, 1% internal; Reserve: 26368K, Max: 256K
[2025-05-22T03:47:25.192+0000][90209][gc,start ] GC(1113) Concurrent reset
[2025-05-22T03:47:25.192+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent reset
[2025-05-22T03:47:25.192+0000][90209][gc,ergo ] GC(1113) Pacer for Reset. Non-Taxable: 512M
[2025-05-22T03:47:25.196+0000][90209][gc ] GC(1113) Concurrent reset 4.145ms
[2025-05-22T03:47:25.197+0000][90209][gc,start ] GC(1113) Pause Init Mark (unload classes)
[2025-05-22T03:47:25.197+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for init marking
[2025-05-22T03:47:25.197+0000][90209][gc,ergo ] GC(1113) Pacer for Mark. Expected Live: 87464K, Free: 333M, Non-Taxable: 34110K, Alloc Tax Rate: 0.3x
[2025-05-22T03:47:25.197+0000][90209][gc ] GC(1113) Pause Init Mark (unload classes) 0.179ms
[2025-05-22T03:47:25.197+0000][90209][safepoint ] Safepoint "ShenandoahInitMark", Time since last: 300009312676 ns, Reaching safepoint: 103422 ns, Cleanup: 34248 ns, At safepoint: 230252 ns, Total: 367922 ns
[2025-05-22T03:47:25.197+0000][90209][gc,start ] GC(1113) Concurrent marking roots
[2025-05-22T03:47:25.197+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent marking roots
[2025-05-22T03:47:25.202+0000][90209][gc ] GC(1113) Concurrent marking roots 5.668ms
[2025-05-22T03:47:25.203+0000][90209][gc,start ] GC(1113) Concurrent marking (unload classes)
[2025-05-22T03:47:25.203+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent marking
[2025-05-22T03:47:25.422+0000][90209][gc ] GC(1113) Concurrent marking (unload classes) 219.748ms
[2025-05-22T03:47:25.422+0000][90209][gc,start ] GC(1113) Pause Final Mark (unload classes)
[2025-05-22T03:47:25.422+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for final marking
[2025-05-22T03:47:25.423+0000][90209][gc,ergo ] GC(1113) Adaptive CSet Selection. Target Free: 74274K, Actual Free: 372M, Max CSet: 21845K, Min Garbage: 0B
[2025-05-22T03:47:25.423+0000][90209][gc,ergo ] GC(1113) Collectable Garbage: 56823K (82%), Immediate: 51200K (74%), CSet: 5623K (8%)
[2025-05-22T03:47:25.423+0000][90209][gc,ergo ] GC(1113) Pacer for Evacuation. Used CSet: 5632K, Free: 383M, Non-Taxable: 39230K, Alloc Tax Rate: 1.1x
[2025-05-22T03:47:25.423+0000][90209][gc ] GC(1113) Pause Final Mark (unload classes) 0.315ms
[2025-05-22T03:47:25.423+0000][90209][safepoint ] Safepoint "ShenandoahFinalMarkStartEvac", Time since last: 225617513 ns, Reaching safepoint: 6612 ns, Cleanup: 18314 ns, At safepoint: 337583 ns, Total: 362509 ns
[2025-05-22T03:47:25.423+0000][90209][gc,start ] GC(1113) Concurrent thread roots
[2025-05-22T03:47:25.423+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for Concurrent thread roots
[2025-05-22T03:47:25.425+0000][90209][gc ] GC(1113) Concurrent thread roots 1.919ms
[2025-05-22T03:47:25.425+0000][90209][gc,start ] GC(1113) Concurrent weak references
[2025-05-22T03:47:25.425+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent weak references
[2025-05-22T03:47:25.425+0000][90209][gc,ref ] GC(1113) Encountered references: Soft: 954, Weak: 14753, Final: 11, Phantom: 1370
[2025-05-22T03:47:25.425+0000][90209][gc,ref ] GC(1113) Discovered references: Soft: 562, Weak: 530, Final: 0, Phantom: 588
[2025-05-22T03:47:25.425+0000][90209][gc,ref ] GC(1113) Enqueued references: Soft: 0, Weak: 2, Final: 0, Phantom: 0
[2025-05-22T03:47:25.425+0000][90209][gc ] GC(1113) Concurrent weak references 0.370ms
[2025-05-22T03:47:25.425+0000][90209][gc,start ] GC(1113) Concurrent weak roots
[2025-05-22T03:47:25.425+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent weak root
[2025-05-22T03:47:25.487+0000][90209][gc ] GC(1113) Concurrent weak roots 62.269ms
[2025-05-22T03:47:25.487+0000][90209][gc,start ] GC(1113) Concurrent cleanup
[2025-05-22T03:47:25.487+0000][90209][gc ] GC(1113) Concurrent cleanup 152M->102M(512M) 0.111ms
[2025-05-22T03:47:25.488+0000][90209][gc,ergo ] GC(1113) Free: 383M, Max: 256K regular, 86016K humongous, Frag: 76% external, 1% internal; Reserve: 26365K, Max: 256K
[2025-05-22T03:47:25.488+0000][90209][gc,start ] GC(1113) Concurrent class unloading
[2025-05-22T03:47:25.488+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent class unloading
[2025-05-22T03:47:25.530+0000][90209][gc ] GC(1113) Concurrent class unloading 42.363ms
[2025-05-22T03:47:25.530+0000][90209][gc,start ] GC(1113) Concurrent strong roots
[2025-05-22T03:47:25.530+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent strong root
[2025-05-22T03:47:25.531+0000][90209][gc ] GC(1113) Concurrent strong roots 0.572ms
[2025-05-22T03:47:25.531+0000][90209][gc,start ] GC(1113) Concurrent evacuation
[2025-05-22T03:47:25.531+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent evacuation
[2025-05-22T03:47:25.531+0000][90209][gc ] GC(1113) Concurrent evacuation 0.239ms
[2025-05-22T03:47:25.531+0000][90209][gc,start ] GC(1113) Pause Init Update Refs
[2025-05-22T03:47:25.531+0000][90209][gc,ergo ] GC(1113) Pacer for Update Refs. Used: 102M, Free: 383M, Non-Taxable: 39229K, Alloc Tax Rate: 1.1x
[2025-05-22T03:47:25.531+0000][90209][gc ] GC(1113) Pause Init Update Refs 0.113ms
[2025-05-22T03:47:25.531+0000][90209][safepoint ] Safepoint "ShenandoahInitUpdateRefs", Time since last: 108115957 ns, Reaching safepoint: 11185 ns, Cleanup: 5288 ns, At safepoint: 145487 ns, Total: 161960 ns
[2025-05-22T03:47:25.531+0000][90209][gc,start ] GC(1113) Concurrent update references
[2025-05-22T03:47:25.531+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for concurrent reference update
[2025-05-22T03:47:25.592+0000][90209][gc ] GC(1113) Concurrent update references 60.728ms
[2025-05-22T03:47:25.592+0000][90209][gc,start ] GC(1113) Concurrent update thread roots
[2025-05-22T03:47:25.594+0000][90209][gc ] GC(1113) Concurrent update thread roots 1.970ms
[2025-05-22T03:47:25.594+0000][90209][gc,start ] GC(1113) Pause Final Update Refs
[2025-05-22T03:47:25.594+0000][90209][gc,task ] GC(1113) Using 1 of 1 workers for final reference update
[2025-05-22T03:47:25.594+0000][90209][gc ] GC(1113) Pause Final Update Refs 0.194ms
[2025-05-22T03:47:25.594+0000][90209][safepoint ] Safepoint "ShenandoahFinalUpdateRefs", Time since last: 62994859 ns, Reaching safepoint: 11346 ns, Cleanup: 4335 ns, At safepoint: 218000 ns, Total: 233681 ns
[2025-05-22T03:47:25.594+0000][90209][gc,start ] GC(1113) Concurrent cleanup
[2025-05-22T03:47:25.594+0000][90209][gc ] GC(1113) Concurrent cleanup 102M->96M(512M) 0.057ms
[2025-05-22T03:47:25.594+0000][90209][gc,ergo ] Free: 388M, Max: 256K regular, 86016K humongous, Frag: 77% external, 1% internal; Reserve: 26368K, Max: 256K
[2025-05-22T03:47:25.594+0000][90209][gc,stats ]
[2025-05-22T03:47:25.594+0000][90209][gc,stats ] All times are wall-clock times, except per-root-class counters, that are sum over
[2025-05-22T03:47:25.594+0000][90209][gc,stats ] all workers. Dividing the <total> over the root stage time estimates parallelism.
[2025-05-22T03:47:25.594+0000][90209][gc,stats ]
[2025-05-22T03:47:25.594+0000][90209][gc,stats ] Concurrent Reset 4195 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Init Mark (G) 514 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Init Mark (N) 192 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Update Region States 63 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Mark Roots 5721 us, parallelism: 0.86x
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CMR: <total> 4925 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CMR: Thread Roots 4423 us, workers (us): 4423,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CMR: VM Strong Roots 36 us, workers (us): 36,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CMR: CLDG Roots 465 us, workers (us): 465,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Marking 219795 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Final Mark (G) 417 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Final Mark (N) 323 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Finish Mark 52 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Update Region States 67 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Choose Collection Set 116 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Rebuild Free Set 15 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Thread Roots 1931 us, parallelism: 0.96x
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CTR: <total> 1861 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CTR: Thread Roots 1861 us, workers (us): 1861,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Weak References 381 us, parallelism: 0.71x
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWRF: <total> 269 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWRF: Weak References 269 us, workers (us): 269,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Weak Roots 62306 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Roots 62133 us, parallelism: 1.00x
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWR: <total> 62082 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWR: Code Cache Roots 60404 us, workers (us): 60404,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWR: VM Weak Roots 1589 us, workers (us): 1589,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CWR: CLDG Roots 89 us, workers (us): 89,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Rendezvous 116 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Cleanup 125 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Class Unloading 42430 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Unlink Stale 40589 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] System Dictionary 106 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Weak Class Links 3 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Code Roots 40475 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Rendezvous 148 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Purge Unlinked 1598 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Code Roots 1558 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CLDG 25 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Exception Caches 0 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Strong Roots 598 us, parallelism: 0.57x
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CSR: <total> 341 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CSR: VM Strong Roots 9 us, workers (us): 9,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] CSR: CLDG Roots 332 us, workers (us): 332,
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Evacuation 260 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Init Update Refs (G) 234 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Init Update Refs (N) 121 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Manage GCLABs 71 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Update Refs 60928 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Update Thread Roots 1987 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Final Update Refs (G) 310 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Pause Final Update Refs (N) 209 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Update Region States 115 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Trash Collection Set 4 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Rebuild Free Set 20 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Concurrent Cleanup 68 us
[2025-05-22T03:47:25.595+0000][90209][gc,stats ]
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] Allocation pacing accrued:
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] 0 of 300407 ms ( 0.0%): <total>
[2025-05-22T03:47:25.595+0000][90209][gc,stats ] 0 of 300407 ms ( 0.0%): <average total>
[2025-05-22T03:47:25.595+0000][90209][gc,stats ]
[2025-05-22T03:47:25.595+0000][90209][gc,metaspace] Metaspace: 128892K(131008K)->128892K(131008K) NonClass: 111531K(112576K)->111531K(112576K) Class: 17360K(18432K)->17360K(18432K)
[2025-05-22T03:47:25.595+0000][90209][gc,ergo ] Pacer for Idle. Initial: 10485K, Alloc Tax Rate: 1.0x
[2025-05-22T03:48:10.598+0000][90209][safepoint ] Safepoint "Cleanup", Time since last: 45003672777 ns, Reaching safepoint: 105762 ns, Cleanup: 87273 ns, At safepoint: 15105 ns, Total: 208140 ns