[Bug]: telemetry truncation time grows with the square of the operation count for size-limited exporters other than LambdaLogExporter
维护者通常 1 天内回复
还没有人认领这个 Issue。
评估
- 难度
- 4/5
- 预计耗时
- 3-5 天
- 新手友好度
- 54/100
- Issue 类型
- 缺陷
- 描述清晰度
- 描述清楚
- 活跃度
- 活跃
- 技术栈
- aws, java
- 领域
- backend, performance
调研方向
Start with telemetry-exporter/src/main/java/software/amazon/lambda/durable/telemetry/Truncation.java, especially truncateRecord and Meter.fits, then trace how DurableExecutionTelemetry.exportRecord chooses the truncation path. Compare the built-in exporter renderings and the bounded meter behavior described in the issue. Done means oversized records truncate without the reported repeated full-record measurement cost while preserving the record format and correct truncation results.
由索引模型根据 Issue 内容生成。
描述
Expected Behavior
For every built-in size-limited exporter, truncating an oversized record takes a small share of the invocation, whatever the number of operations.
Actual Behavior
DurableExecutionTelemetry.exportRecord truncates with Truncation.truncateMeasured for LambdaLogExporter, and with Truncation.truncateRecord for every other exporter. truncateRecord passes bounded = false. So when a rendered record is over its limit, Meter.fits measures it before it considers each operation, once in the result step and again in the operation step. The work grows with the square of the operation count. A record that already fits returns after one measurement.
With the limit and rendering of CloudWatchLogsExporter (256,000 bytes, operations by name), truncating a record of 4,000 simple successful steps took 15.4 s on JDK 21.0.13 at tag v3.0.1. With the same limit and the array rendering, it took 3.5 s for 2,000 operations and 14.8 s for 4,000. The LambdaLogExporter path truncated a 30,000-operation record in 0.6 s.
Every other built-in exporter with a default limit takes the slow path when a record is over its limit: CloudWatchLogsExporter, SQSExporter and EventBridgeExporter (256,000 bytes), DynamoDBExporter (400,000), FirehoseExporter, AuroraExporter, RedshiftExporter and OTelExporter (1,000,000), S3Exporter (5,000,000) and OpenSearchExporter (10,000,000). FileExporter and HttpExporter take it too when a limit is configured.
The time is spent before the invocation returns:
TelemetryPlugin.onInvocationEndwaits for the export throughExportScheduler.drain, which has no deadline.DurableExecutorcallsonInvocationEndbefore it returns the invocation output.- The final record also includes operations from earlier invocations. With the default settings it lists every named top-level operation of the execution. So a long execution truncates its accumulated operations when it ends.
- With
EmitMode.ON_CHANGE, the plugin also exports records during the invocation.
Steps to Reproduce
Check out tag v3.0.1. Add this test to telemetry-exporter/src/test/java/software/amazon/lambda/durable/telemetry/, then run mvn -pl telemetry-exporter -am -Djacoco.skip=true -Dtest=TruncationTimeTest -Dsurefire.failIfNoSpecifiedTests=false test:
package software.amazon.lambda.durable.telemetry;
import java.time.Instant;
import java.time.ZoneOffset;
import java.time.format.DateTimeFormatter;
import org.junit.jupiter.api.Test;
class TruncationTimeTest {
private static final DateTimeFormatter TS =
DateTimeFormatter.ofPattern("yyyy-MM-dd'T'HH:mm:ss.SSS'Z'").withZone(ZoneOffset.UTC);
@Test
void truncateRecordTime() {
DurableExecutionTelemetryRecord record = new DurableExecutionTelemetryRecord();
record.executionArn =
"arn:aws:lambda:us-east-1:123456789012:function:demo:$LATEST/durable-execution/run-1/inv-1";
record.status = "SUCCEEDED";
Instant start = Instant.parse("2026-10-28T00:00:00Z");
for (int i = 0; i < 4000; i++) {
Instant s = start.plusMillis(i * 10L);
record.operations.add(new OperationRecord()
.id("op-" + i)
.name("step-" + i)
.type("STEP")
.subType("Step")
.status("SUCCEEDED")
.startTime(TS.format(s))
.endTime(TS.format(s.plusMillis(3)))
.durationMs(3L)
.attempt(1));
}
// CloudWatchLogsExporter's limit and rendering: 256,000 bytes, operations by name.
long t0 = System.nanoTime();
DurableExecutionTelemetryRecord out =
Truncation.truncateRecord(record, 256_000, DurableExecutionTelemetryRecord::toByNameWireMap);
System.out.printf(
"truncateRecord: %.1f s, kept %d operations, dropped %d%n",
(System.nanoTime() - t0) / 1e9, out.operations().size(), out.droppedOperations);
}
}
It prints a time of about 15 s and kept 1568 operations, dropped 2432.
SDK Version
aws-durable-execution-sdk-java-plugin-telemetry-exporter 3.0.1. main has the same truncation code.
Java Version
21
Is this a regression?
No
Additional Context
Two changes would remove most of the cost. Neither changes the public API or the record format.
- Measure once after the error causes are removed. Then, in the result step, measure again only after an operation loses its result. With the default content settings no operation has a result. So today that step measures the full record once per operation and drops nothing.
- Use the bounded meter for the built-in renderings whose per-drop shrink has a known upper bound.
truncateMeasureddocuments that bound for the record's JSON and for that JSON embedded as one string value. The array and by-name wire maps fit it, and so does the OTel body, which embeds the rendered record as a string.OperationsFormat.BOTHholds the operations array and the derived by-name map, so its bound must cover the shrink of both. A customrenderkeeps exact checks. For a rendering that the bound covers, the bounded meter returns the same record as checking after every drop.
The Go SDK had the same problem (Go SDK #153: Truncate re-renders the whole record per dropped operation) and fixed it with a binary search over the drop count (Go SDK PR #154: binary-search the Truncate drop count).
Code at the commit I read:
- 主要语言
- Java
- 星标
- 28
- 派生
- 18
- 平均合并
- 18 小时 16 分钟
- 30 天内合并 PR
- 62
环境准备
- 没有 Dockerfile 或 Docker Compose 文件
- 有 Pull Request 模板
- 阅读贡献指南
从这里开始
- 先读完整个 Issue,再读项目的贡献指南。
- 在 Issue 下留言说明你要接手 —— 这能避免两个人做同样的事。
- Fork 仓库,在一个分支上完成修改。
- 提交 Pull Request,并在描述里引用这个 Issue 编号。
aws/aws-durable-execution-sdk-java 的其他 Issue
-
bug pkg:conformance
难度 2/5 1-3 小时 新手友好度 76/100
aws/aws-durable-execution-sdk-java#844 ·
维护者通常 1 天内回复
-
documentation pkg:sdk
难度 1/5 1-3 小时 新手友好度 88/100
aws/aws-durable-execution-sdk-java#645 · 1 条评论 ·
维护者通常 1 天内回复
-
enhancement
难度 2/5 1-3 小时 新手友好度 68/100
aws/aws-durable-execution-sdk-java#300 ·
维护者通常 1 天内回复
-
bug needs-triage pkg:sdk
难度 5/5 一周以上 新手友好度 35/100
aws/aws-durable-execution-sdk-java#859 ·
维护者通常 1 天内回复
-
bug needs-triage pkg:otel
难度 4/5 3-5 天 新手友好度 48/100
aws/aws-durable-execution-sdk-java#858 · 1 条评论 ·
维护者通常 1 天内回复
查看 aws/aws-durable-execution-sdk-java 的全部 Issue
相似的 Issue
-
API `/api/dataverses/{identifier}/dataset/linkingDataverses?alreadyLinking=true` returns at most 1 collection可能已有人在做 关联的 PR 仍在进行中或已合并。 未关闭Type: Bug
难度 2/5 1-3 小时 新手友好度 76/100
维护者通常 1 天内回复
-
javaoperatorsdk.client.oauth-token has no effect on the Kubernetes client可能已有人在做 @RaphaelFakhri 今天认领。 未关闭
难度 2/5 1-3 小时 新手友好度 82/100
-
MCP add-on: JSON responses with non-ASCII characters are truncated (wrong Content-Length)可能已有人在做 @dhanushbs10 今天认领。 未关闭add-on bug in:mcp
难度 2/5 1-3 小时 新手友好度 78/100
维护者通常 1 天内回复
-
Map.Coordinate does not implement proper equals可能已有人在做 关联的 PR 仍在进行中或已合并。 未关闭vaadin-map
难度 2/5 1-3 小时 新手友好度 70/100
vaadin/flow-components#10586 ·
维护者通常 1 天内回复
-
WeReadInstaller writes the launcher database on the main thread at handover (Activity pause timeout)未关闭bug good first issue
难度 2/5 1-3 小时 新手友好度 70/100