void MM_VerboseHandlerOutput::handleInitialized(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_InitializedEvent* event = (MM_InitializedEvent*)eventData; MM_VerboseWriterChain* writer = _manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); char tagTemplate[200]; _manager->setInitializedTime(event->timestamp); getTagTemplate(tagTemplate, sizeof(tagTemplate), _manager->getIdAndIncrement(), omrtime_current_time_millis()); enterAtomicReportingBlock(); writer->formatAndOutput(env, 0, "<initialized %s>", tagTemplate); writer->formatAndOutput(env, 1, "<attribute name=\"gcPolicy\" value=\"%s\" />", event->gcPolicy); #if defined(OMR_GC_CONCURRENT_SCAVENGER) if (_extensions->isConcurrentScavengerEnabled()) { writer->formatAndOutput(env, 1, "<attribute name=\"concurrentScavenger\" value=\"%s\" />", #if defined(S390) _extensions->concurrentScavengerHWSupport ? "enabled, with H/W assistance" : "enabled, without H/W assistance"); #else /* defined(S390) */ "enabled"); #endif /* defined(S390) */ }
void MM_VerboseHandlerOutputStandard::handleConcurrentHalted(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_ConcurrentHaltedEvent* event = (MM_ConcurrentHaltedEvent*)eventData; MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); char tagTemplate[200]; enterAtomicReportingBlock(); getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<concurrent-halted %s>", tagTemplate); handleConcurrentHaltedInternal(env, eventData); writer->formatAndOutput(env, 1, "<traced " "bytesTarget=\"%zu\" bytesTotal=\"%zu\" " "bytesByMutator=\"%zu\" bytesByHelper=\"%zu\" " "percent=\"%zu\" />", event->traceTarget, event->tracedTotal, event->tracedByMutators, event->tracedByHelpers, event->traceTarget == 0 ? 0 : (uintptr_t)(((uint64_t)event->tracedTotal * 100) / (uint64_t)event->traceTarget)); writer->formatAndOutput(env, 1, "<cards cleaned=\"%zu\" thresholdBytes=\"%zu\" />", event->cardsCleaned, event->cardCleaningThreshold); writer->formatAndOutput(env, 0, "</concurrent-halted>"); writer->flush(env); exitAtomicReportingBlock(); }
void MM_VerboseHandlerOutputStandard::handleConcurrentKickoff(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_ConcurrentKickoffEvent* event = (MM_ConcurrentKickoffEvent*)eventData; MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); MM_GCExtensionsBase* extensions = MM_GCExtensionsBase::getExtensions(env->getOmrVM()); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); char tagTemplate[200]; enterAtomicReportingBlock(); getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<concurrent-kickoff %s>", tagTemplate); const char* reasonString = getConcurrentKickoffReason(eventData); if (extensions->scavengerEnabled) { writer->formatAndOutput( env, 1, "<kickoff reason=\"%s\" targetBytes=\"%zu\" thresholdFreeBytes=\"%zu\" remainingFree=\"%zu\" tenureFreeBytes=\"%zu\" nurseryFreeBytes=\"%zu\" />", reasonString, event->traceTarget, event->kickOffThreshold, event->remainingFree, event->commonData->tenureFreeBytes, event->commonData->nurseryFreeBytes); } else { writer->formatAndOutput( env, 1, "<kickoff reason=\"%s\" targetBytes=\"%zu\" thresholdFreeBytes=\"%zu\" remainingFree=\"%zu\" tenureFreeBytes=\"%zu\" />", reasonString, event->traceTarget, event->kickOffThreshold, event->remainingFree, event->commonData->tenureFreeBytes); } writer->formatAndOutput(env, 0, "</concurrent-kickoff>"); writer->flush(env); handleConcurrentKickoffInternal(env, eventData); exitAtomicReportingBlock(); }
void MM_VerboseHandlerOutputStandard::handleGCOPOuterStanzaStart(MM_EnvironmentBase* env, const char *type, uintptr_t contextID, uint64_t duration, bool deltaTimeSuccess) { MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); OMRPORT_ACCESS_FROM_OMRPORT(env->getPortLibrary()); if (!deltaTimeSuccess) { writer->formatAndOutput(env, 0, "<warning details=\"clock error detected, following timing may be inaccurate\" />"); } char tagTemplate[200]; getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), type ,contextID, duration, omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<gc-op %s>", tagTemplate); }
void MM_VerboseHandlerOutputStandard::handleConcurrentCollectionEnd(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_ConcurrentCollectionEndEvent* event = (MM_ConcurrentCollectionEndEvent*)eventData; MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); char tagTemplate[200]; enterAtomicReportingBlock(); getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<concurrent-collection-end %s />", tagTemplate); writer->flush(env); handleConcurrentCollectionEndInternal(env, eventData); exitAtomicReportingBlock(); }
void MM_VerboseHandlerOutputStandard::handleScavengePercolate(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_PercolateCollectEvent *event = (MM_PercolateCollectEvent *)eventData; MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_OMRPORT(env->getPortLibrary()); MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); char tagTemplate[200]; getTagTemplate(tagTemplate, sizeof(tagTemplate), omrtime_current_time_millis()); enterAtomicReportingBlock(); writer->formatAndOutput(env, 0, "<percolate-collect id=\"%zu\" from=\"%s\" to=\"%s\" reason=\"%s\" %s/>", manager->getIdAndIncrement(), "nursery", "global", getPercolateReasonAsString((PercolateReason)event->reason), tagTemplate); writer->flush(env); handleScavengePercolateInternal(env, eventData); exitAtomicReportingBlock(); }
void MM_VerboseHandlerOutputStandard::handleConcurrentCollectionStart(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_ConcurrentCollectionStartEvent* event = (MM_ConcurrentCollectionStartEvent*)eventData; MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); uint64_t currentTime = event->timestamp; uint64_t previousTime = manager->getLastConcurrentGCTime(); manager->setLastConcurrentGCTime(currentTime); if (0 == previousTime) { previousTime = manager->getInitializedTime(); } uint64_t deltaTime = omrtime_hires_delta(previousTime, currentTime, OMRPORT_TIME_DELTA_IN_MICROSECONDS); const char* cardCleaningReasonString = "unknown"; switch (event->cardCleaningReason) { case TRACING_COMPLETED: cardCleaningReasonString = "tracing completed"; break; case CARD_CLEANING_THRESHOLD_REACHED: cardCleaningReasonString = "card cleaning threshold reached"; break; } char tagTemplate[200]; enterAtomicReportingBlock(); getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<concurrent-collection-start %s intervalms=\"%llu.%03llu\" >", tagTemplate, deltaTime / 1000, deltaTime % 1000); writer->formatAndOutput(env, 1, "<concurrent-trace-info reason=\"%s\" tracedByMutators=\"%zu\" tracedByHelpers=\"%zu\" cardsCleaned=\"%zu\" workStackOverflowCount=\"%zu\" />", cardCleaningReasonString, event->tracedByMutators, event->tracedByHelpers, event->cardsCleaned, event->workStackOverflowCount); writer->formatAndOutput(env, 0, "</concurrent-collection-start>"); writer->flush(env); handleConcurrentCollectionStartInternal(env, eventData); exitAtomicReportingBlock(); }
void MM_VerboseHandlerOutputStandard::handleConcurrentAborted(J9HookInterface** hook, uintptr_t eventNum, void* eventData) { MM_ConcurrentAbortedEvent* event = (MM_ConcurrentAbortedEvent*)eventData; MM_VerboseManager* manager = getManager(); MM_VerboseWriterChain* writer = manager->getWriterChain(); MM_EnvironmentBase* env = MM_EnvironmentBase::getEnvironment(event->currentThread); OMRPORT_ACCESS_FROM_ENVIRONMENT(env); char tagTemplate[100]; enterAtomicReportingBlock(); getTagTemplate(tagTemplate, sizeof(tagTemplate), manager->getIdAndIncrement(), omrtime_current_time_millis()); writer->formatAndOutput(env, 0, "<concurrent-aborted %s>", tagTemplate); const char* reason; switch((CollectionAbortReason)event->reason) { case ABORT_COLLECTION_INSUFFICENT_PROGRESS: reason = "insufficient progress made"; break; case ABORT_COLLECTION_REMEMBERSET_OVERFLOW: reason = "remembered set overflow"; break; case ABORT_COLLECTION_SCAVENGE_REMEMBEREDSET_OVERFLOW: reason = "scavenge remembered set overflow"; break; case ABORT_COLLECTION_PREPARE_HEAP_FOR_WALK: reason = "prepare heap for walk"; break; default: reason = "unknown"; break; } writer->formatAndOutput(env, 1, "<reason value=\"%s\" />", reason); writer->formatAndOutput(env, 0, "</concurrent-aborted>"); writer->flush(env); handleConcurrentAbortedInternal(env, eventData); exitAtomicReportingBlock(); }