非同期ジョブが13分かかってもタイムアウトしない理由——Dataverse の「拾われ待ち」と「実行時間」を分けて診断する
特定の PoC 環境で、Dataverse 非同期ジョブのキュー待ちが最大13分に達したとき、タイムアウトは1件も発生しなかった。「2分制限のはずでは」と思ったとしたら、診断の起点が間違っている。 タイムアウトは実行時間にしかかからない。ログで見ている「総経過時間」は、キューに放り込んでから完了するまでの wall-clock であり、その大半が「拾われ待ち(ディスパッチ待ち)」である可能性がある。この区別が曖昧なまま診断すると、問題のないジョブを危険視するか、本当に問題のある実行時間の長さを見逃す。 結論を先に示す。Dataverse の AsyncOperation テーブルには CreatedOn / StartedOn / CompletedOn の3時刻フィールドがある。これを分離して計測すれば、総経過時間の内訳がわかり、タイムアウト診断が正確になる。 13分待っても、タイムアウトしないDataverse の非同期サービスは FIFO(先入れ先出し)のマネージドキューを持つ。ジョブが登録されると、キューに投入され、非同期サービスが「拾いに来る」まで待つ。この待ち時間はタイムアウトのカウントに含まれない。 Microsoft Learn の公式ドキュメント(Asynchronous service (Microsoft Dataverse)、2025年確認)には次の記述がある。 sending the request asynchronously doesn’t provide more execution time 非同期登録にしても、実行タイムアウト(プラグイン・ワークフロー共通で2分=120秒)は変わらない。制限されているのはあくまで「実行時間」であり、「キューに入ってから拾われるまでの待ち時間」は制限の対象外だ。 レストランの席待ちに例えると、タイムアウト2分は「席に着いてから注文・食事・会計までの時間制限」だ。席待ちで40分並んでいても、それはタイムアウトに数えられない。13分のキュー待ちがあっても、その後の実行が2分以内に収まれば、タイムアウトは発生しない。 ただし、上記の「最大13分のキュー待ち」は特定の PoC 環境での観察値であり、本番環境や他製品での再現を保証するものではない。 判断軸:拾われ待ちと実行時間は別物だ非同期ジョブの経過時間は、大きく2つに分解できる。 区間 内容 タイムアウト対象か 拾われ待ち キュー投入 → 実行開始 対象外 実行時間 実行開始 → 実行完了 対象(2分・120秒) タイムアウト診断で見るべきは、右列が「対象」の実行時間だけだ。ログの総経過時間を見ていると、拾われ待ちの長さがそのまま「危険なシグナル」に見えてしまう。逆に、実行時間が2分に近づいていても、総経過時間が短ければ見過ごす可能性がある。 計測の単位を間違えると、診断の方向が最初から狂う。 AsyncOperation テーブルで3時刻を分離するDataverse の AsyncOperation テーブル(System Job (AsyncOperation) table/entity reference、2025年確認)には、以下のフィールドが存在する。 フィールド 意味 診断での役割 CreatedOn ジョブ作成(キュー投入)時刻 拾われ待ちの開始点 StartedOn 実行開始時刻 拾われ待ちの終点・タイムアウトカウント開始 CompletedOn 実行完了時刻 実行時間の終点 ExecutionTimeSpan 実行時間(数値) タイムアウト評価の直接対象 計算式はシンプルだ。 ...