/* * Copyright (c) 2008-2017, Hazelcast, Inc. All Rights Reserved. * * Licensed under the Apache License, Version 2.0 (the "License"); * you may not use this file except in compliance with the License. * You may obtain a copy of the License at * * http://www.apache.org/licenses/LICENSE-2.0 * * Unless required by applicable law or agreed to in writing, software * distributed under the License is distributed on an "AS IS" BASIS, * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. * See the License for the specific language governing permissions and * limitations under the License. */ package com.hazelcast.internal.diagnostics; import com.hazelcast.internal.management.dto.SlowOperationDTO; import com.hazelcast.internal.management.dto.SlowOperationInvocationDTO; import com.hazelcast.spi.impl.NodeEngineImpl; import com.hazelcast.spi.impl.operationservice.InternalOperationService; import com.hazelcast.spi.properties.GroupProperty; import com.hazelcast.spi.properties.HazelcastProperties; import com.hazelcast.spi.properties.HazelcastProperty; import java.util.List; import static com.hazelcast.internal.diagnostics.Diagnostics.PREFIX; import static com.hazelcast.util.StringUtil.LINE_SEPARATOR; import static java.util.concurrent.TimeUnit.SECONDS; /** * A {@link DiagnosticsPlugin} that displays the slow executing operations. For more information see * {@link com.hazelcast.spi.impl.operationexecutor.slowoperationdetector.SlowOperationDetector}. */ public class SlowOperationPlugin extends DiagnosticsPlugin { /** * The period in seconds the SlowOperationPlugin runs. * * With the slow operation plugin, slow executing operation can be found. This is done by checking * on the caller side which operations take a lot of time executing. * * This plugin is very cheap to use. * * If set to 0, the plugin is disabled. */ public static final HazelcastProperty PERIOD_SECONDS = new HazelcastProperty( PREFIX + ".slowoperations.period.seconds", 60, SECONDS); private final InternalOperationService operationService; private final long periodMillis; public SlowOperationPlugin(NodeEngineImpl nodeEngine) { super(nodeEngine.getLogger(SlowOperationPlugin.class)); this.operationService = nodeEngine.getOperationService(); this.periodMillis = getPeriodMillis(nodeEngine); } private long getPeriodMillis(NodeEngineImpl nodeEngine) { HazelcastProperties props = nodeEngine.getProperties(); if (!props.getBoolean(GroupProperty.SLOW_OPERATION_DETECTOR_ENABLED)) { return DISABLED; } return props.getMillis(PERIOD_SECONDS); } @Override public long getPeriodMillis() { return periodMillis; } @Override public void onStart() { logger.info("Plugin:active, period-millis:" + periodMillis); } @Override public void run(DiagnosticsLogWriter writer) { List<SlowOperationDTO> slowOperations = operationService.getSlowOperationDTOs(); writer.startSection("SlowOperations"); if (slowOperations.size() > 0) { for (SlowOperationDTO slowOperation : slowOperations) { render(writer, slowOperation); } } writer.endSection(); } private void render(DiagnosticsLogWriter writer, SlowOperationDTO slowOperation) { writer.startSection(slowOperation.operation); writer.writeKeyValueEntry("invocations", slowOperation.totalInvocations); renderStackTrace(writer, slowOperation); renderInvocations(writer, slowOperation); writer.endSection(); } private void renderInvocations(DiagnosticsLogWriter writer, SlowOperationDTO slowOperation) { writer.startSection("slowInvocations"); for (SlowOperationInvocationDTO invocation : slowOperation.invocations) { writer.writeKeyValueEntry("startedAt", invocation.startedAt); writer.writeKeyValueEntry("durationNs", invocation.durationMs); writer.writeKeyValueEntry("operationDetails", invocation.operationDetails); } writer.endSection(); } private void renderStackTrace(DiagnosticsLogWriter writer, SlowOperationDTO slowOperation) { writer.startSection("stackTrace"); // this is quite inefficient due to object allocations; it would be cheaper to manually traverse String[] stackTraceLines = slowOperation.stackTrace.split(LINE_SEPARATOR); for (String stackTraceLine : stackTraceLines) { writer.writeEntry(stackTraceLine); } writer.endSection(); } }