AzureStorage: a permanent history-load failure in ScheduleOrchestrationStatePrefetch increases memory without limit
メンテナーはふだん 3 日以内に返信
評価
- 難易度
- 3/5
- 見積もり時間
- 1〜2日
- 初心者へのやさしさ
- 35/100
調査の方向性
Start in OrchestrationSessionManager.ScheduleOrchestrationStatePrefetch, in the catch block that logs OrchestrationProcessingFailure, sleeps, and then awaits Task.Run on a recursive call. The retry chain is what keeps each failed attempt's exception alive. The issue says a fix is already in #1418, so check that pull request before starting. Done means a history-load failure that never recovers no longer grows the set of retained exceptions, which the always-failing tracking-store test in #1418 checks.
索引モデルが issue の本文から書いたものです。
説明
Summary
When OrchestrationSessionManager.ScheduleOrchestrationStatePrefetch cannot load the history of a pending batch, it logs OrchestrationProcessingFailure and waits 5 seconds. Then it awaits a recursive call of itself in the catch block:
catch (Exception e)
{
this.settings.Logger.OrchestrationProcessingFailure(...);
// Sleep briefly to avoid a tight failure loop.
await Task.Delay(TimeSpan.FromSeconds(5));
// This is a background operation so failure is not an option. All exceptions must be handled.
// To avoid starvation, we need to re-enqueue this async operation instead of retrying in a loop.
await Task.Run(() => this.ScheduleOrchestrationStatePrefetch(node, traceActivityId, cancellationToken));
}
If the failure is permanent, no attempt completes. Each failed attempt then stays in memory, and memory increases without a limit while the process runs.
Cause
- The
catchblock containsawait. Thus, the compiler keeps the caught exception in a state-machine field (<>7__wrap2). The code clears this field only after the awaited recursive call completes. Task.Run(Func<Task>)returns a proxy task (UnwrapPromise). The proxy completes only when the inner task completes.- Thus, each attempt keeps the previous attempt. Each exception keeps its
Azure.RequestFailedExceptionand theAzure.Response(HTTP request, response, headers, and content stream). - The path from the GC root is: timer queue ->
Task+DelayPromise(the 5-second delay) -> newest state machine ->UnwrapPromise-> older state machine -> ... -> oldest state machine.
The awaited recursion came from #633. Before #633, the retry put a new work item in a DispatchQueue and then returned. Thus, a failed attempt did not wait for the next attempt.
Affected versions
DurableTask.AzureStorage 1.9.4 and later. The retry code is the same in 2.0.0, 2.10.0, and main (99296e0f).
What we saw
- A worker process on DurableTask.AzureStorage 2.0.0 (.NET 10) ran for 14 days. A full-memory dump had 239,392 live
AsyncStateMachineBox<...<ScheduleOrchestrationStatePrefetch>d__25>objects. It had the same number ofDurableTaskStorageException,Azure.RequestFailedException,HttpRequestMessage, andHttpResponseMessageobjects. Together they used about 2.7 GB, which was almost all of the live managed heap. - One orchestration instance caused all of it. Its history referred to a large-message blob that did not exist (404
BlobNotFound). 239,392 attempts x 5.04 seconds = the process uptime.
Repro
Use Microsoft.Azure.DurableTask.AzureStorage 2.0.0, Microsoft.Azure.DurableTask.Core 3.0.0, and Azurite:
- Start an orchestration that calls an activity. The activity returns a string of 50,000 characters, so the History row stores the result in the
{taskhub}-largemessagescontainer. Then the orchestration waits for an external event. - Delete the result blob of that History row.
- Raise an event to the instance.
Result:
| Time after the event | Failed attempts | Live prefetch state machines | Live exception pairs |
|---|---|---|---|
| 60 s | 12 | 12 | 12 |
| 210 s | 40 | 40 | 40 |
The heap increased by about 12 KB for each attempt. When the same blob was uploaded again, the next attempt was successful, and all the retained objects were released.
The new unit test in #1418 shows the same behavior with a tracking store that always fails. Without the fix, all 19 earlier exceptions stay in memory.
Triggers
Each history-load failure that does not stop causes this problem. Examples:
- A History row refers to a large-message blob that does not exist. In 2.0.0, one cause is a late message for an execution that
ContinueAsNewreplaced. #1189 fixed that case in 2.1.0. A missing blob of the current execution has the same effect in 2.10.0 andmain. - A permanent authorization failure (403), a missing History table, or invalid event data.
Other notes
- No setting controls this retry. The delay does not use the cancellation token.
- A partition lease move does not stop the retries. A new owner of the partition can start its own chain for the same instance.
Related
- #1215 (the same stack trace), #802, and #1189.
- Fix: #1418
- 主要言語
- C#
- スター
- 1.7k
- フォーク
- 338
- 平均マージ
- 4日 13時間
- マージ済み PR(30日)
- 10
環境構築
このプロジェクトには開発コンテナ、Dockerfile、コントリビューションガイドがありません。まず README を読み、一般的な手順ははじめてのコントリビューションガイドを参照してください。
はじめの一歩
- issue を最後まで読み、次にプロジェクトのコントリビューションガイドを読みます。
- 着手することを issue にコメントします — 二人が同じ作業をするのを防げます。
- リポジトリをフォークし、ブランチを切って変更します。
- issue 番号を参照したプルリクエストを送ります。
Azure/durabletask のほかの issue
-
Azure Storage backend: ExecutionTerminated message is never deleted when the Instances row is absent, causing permanent control-queue poison (regression in 2.7.0 from #1256)対応中かも @sean0407 が 5 日前に担当しました。 オープン
難易度 4/5 3〜5日 初心者へのやさしさ 55/100
Azure/durabletask#1398 · コメント 2 件 ·
メンテナーはふだん 3 日以内に返信
-
Azure Storage backend: control queue partition left unowned for hours/days after lease expires再び着手できるかも @nytian が 47 日前に担当しましたが、オープン中のプルリクエストはありません。 オープン
Azure/durabletask#1389 · コメント 1 件 · 担当者 1 名 ·
メンテナーはふだん 3 日以内に返信
-
難易度 4/5 3〜5日 初心者へのやさしさ 48/100
Azure/durabletask#1332 ·
メンテナーはふだん 3 日以内に返信
-
難易度 3/5 1〜2日 初心者へのやさしさ 58/100
Azure/durabletask#1318 · コメント 1 件 ·
メンテナーはふだん 3 日以内に返信
-
難易度 5/5 1週間以上 初心者へのやさしさ 25/100
Azure/durabletask#1301 · コメント 3 件 ·
メンテナーはふだん 3 日以内に返信
Azure/durabletask の issue をすべて見る
似ている issue
-
難易度 2/5 1〜3時間 初心者へのやさしさ 78/100
メンテナーはふだん 1 日以内に返信
-
難易度 2/5 1〜3時間 初心者へのやさしさ 85/100
メンテナーはふだん 1 日以内に返信
-
dette technique priorité: basse zone: service
難易度 2/5 1〜3時間 初心者へのやさしさ 68/100
damiensabau/nexus-lobby#25 ·
-
難易度 2/5 1〜3時間 初心者へのやさしさ 78/100
sebastienros/jint#4282 ·
メンテナーはふだん 1 日以内に返信
-
難易度 1/5 1時間未満 初心者へのやさしさ 76/100
pyrevitlabs/pyRevit#3792 ·
メンテナーはふだん 1 日以内に返信