Skip to content
Open
Show file tree
Hide file tree
Changes from all commits
Commits
File filter

Filter by extension

Filter by extension

Conversations
Failed to load comments.
Loading
Jump to
Jump to file
Failed to load files.
Loading
Diff view
Diff view
Original file line number Diff line number Diff line change
Expand Up @@ -10,7 +10,6 @@
import io.temporal.api.workflowservice.v1.GetSystemInfoResponse;
import io.temporal.api.workflowservice.v1.PollActivityTaskQueueRequest;
import io.temporal.api.workflowservice.v1.PollActivityTaskQueueResponse;
import io.temporal.internal.common.ProtobufTimeUtils;
import io.temporal.serviceclient.MetricsTag;
import io.temporal.serviceclient.WorkflowServiceStubs;
import io.temporal.worker.MetricsType;
Expand Down Expand Up @@ -120,11 +119,6 @@ public ActivityTask poll() {
metricsScope.counter(MetricsType.ACTIVITY_POLL_NO_TASK_COUNTER).inc(1);
return null;
}
metricsScope
.timer(MetricsType.ACTIVITY_SCHEDULE_TO_START_LATENCY)
.record(
ProtobufTimeUtils.toM3Duration(
response.getStartedTime(), response.getCurrentAttemptScheduledTime()));
isSuccessful = true;
pollerTracker.pollSucceeded();
return new ActivityTask(
Expand Down
Original file line number Diff line number Diff line change
Expand Up @@ -333,6 +333,13 @@ public void handle(ActivityTask task) throws Exception {
ActivityTaskHandler.Result result = null;
boolean taskFailed = false;
try {
// Schedule to start latency does not depend on the activity type, so it is reported with
// the worker scope.
workerMetricsScope
.timer(MetricsType.ACTIVITY_SCHEDULE_TO_START_LATENCY)
.record(
ProtobufTimeUtils.toM3Duration(
pollResponse.getStartedTime(), pollResponse.getCurrentAttemptScheduledTime()));
result = handleActivity(task, metricsScope);
if (result.getTaskFailed() != null
&& !io.temporal.internal.common.FailureUtils.isBenignApplicationFailure(
Expand Down Expand Up @@ -382,12 +389,6 @@ private ActivityTaskHandler.Result handleActivity(ActivityTask task, Scope metri
false);
}
PollActivityTaskQueueResponseOrBuilder pollResponse = task.getResponse();
metricsScope
.timer(MetricsType.ACTIVITY_SCHEDULE_TO_START_LATENCY)
.record(
ProtobufTimeUtils.toM3Duration(
pollResponse.getStartedTime(), pollResponse.getCurrentAttemptScheduledTime()));

ActivityTaskHandler.Result result;

Stopwatch sw = metricsScope.timer(MetricsType.ACTIVITY_EXEC_LATENCY).start();
Expand Down
Original file line number Diff line number Diff line change
Expand Up @@ -12,7 +12,6 @@
import io.temporal.api.workflowservice.v1.PollActivityTaskQueueRequest;
import io.temporal.api.workflowservice.v1.PollActivityTaskQueueResponse;
import io.temporal.internal.common.GrpcUtils;
import io.temporal.internal.common.ProtobufTimeUtils;
import io.temporal.serviceclient.MetricsTag;
import io.temporal.serviceclient.WorkflowServiceStubs;
import io.temporal.worker.MetricsType;
Expand Down Expand Up @@ -121,11 +120,6 @@ public CompletableFuture<ActivityTask> poll(SlotPermit permit) {
return null;
}
pollerTracker.pollSucceeded();
metricsScope
.timer(MetricsType.ACTIVITY_SCHEDULE_TO_START_LATENCY)
.record(
ProtobufTimeUtils.toM3Duration(
r.getStartedTime(), r.getCurrentAttemptScheduledTime()));
return new ActivityTask(
r,
permit,
Expand Down
Original file line number Diff line number Diff line change
Expand Up @@ -39,6 +39,9 @@ public synchronized void assertNoMetric(String name, Map<String, String> tags) {
+ counters.get(metricName).get()
+ "'");
}
if (timers.containsKey(metricName)) {
fail("Timer '" + metricName + "' was reported");
}
}

public synchronized void assertCounter(String name, Map<String, String> tags, long expected) {
Expand Down Expand Up @@ -89,6 +92,20 @@ public synchronized void assertTimer(String name, Map<String, String> tags) {
}
}

public synchronized void assertTimer(
String name, Map<String, String> tags, Predicate<StatsAccumulator> isExpected) {
String metricName = getMetricName(name, tags);
StatsAccumulator value = timers.get(metricName);
if (value == null) {
fail(
"No metric '"
+ metricName
+ "', reported metrics: \n "
+ String.join("\n ", timers.keySet()));
}
assertTrue(metricName + " count: " + value.count(), isExpected.test(value));
}

public synchronized void assertTimerMinDuration(
String name, Map<String, String> tags, Duration minDuration) {
String metricName = getMetricName(name, tags);
Expand Down
Original file line number Diff line number Diff line change
Expand Up @@ -307,6 +307,18 @@ public void testWorkflowMetrics() throws InterruptedException {
reporter.assertCounter(TEMPORAL_REQUEST, workflowTags, 1);
reporter.assertTimer(TEMPORAL_REQUEST_LATENCY, workflowTags);

// The workflow ran a single activity task. Schedule to start latency is independent of the
// activity type, so it must be reported exactly once and without the activity type tags.
reporter.assertTimer(
ACTIVITY_SCHEDULE_TO_START_LATENCY, TAGS_ACTIVITY_WORKER, stats -> stats.count() == 1);
reporter.assertNoMetric(
ACTIVITY_SCHEDULE_TO_START_LATENCY,
new ImmutableMap.Builder<String, String>()
.putAll(TAGS_ACTIVITY_WORKER)
.put(MetricsTag.ACTIVITY_TYPE, "Execute")
.put(MetricsTag.WORKFLOW_TYPE, "NoArgsWorkflow")
.build());

Map<String, String> workflowTaskCompletionTags =
new ImmutableMap.Builder<String, String>()
.putAll(TAGS_WORKFLOW_WORKER)
Expand Down
Loading