SQL扩展事件：sp_statement_completed与rpc_completed间可捕获事件咨询

针对你的问题，确实存在多个可在sp_statement_completed（存储过程内单条语句完成）和rpc_completed（整个RPC调用完成）之间触发的扩展事件，结合Azure SQL和本地环境的差异，以下是具体说明和排查思路：

可捕获的关键中间事件 #

• sql_statement_completed：注意和sp_statement_completed的区别——后者仅针对存储过程内部的语句，而前者会捕获所有SQL语句的完成事件，包括RPC调用中存储过程执行后可能触发的后续隐式系统操作。
• transaction_log_write_completed：存储过程语句执行完毕后，事务日志写入的完成事件，Azure SQL的远程存储日志写入耗时通常远高于本地，是常见的差异来源。
• wait_info：捕获各类等待事件（如LOG_IO_COMPLETION、PAGEIOLATCH_*、CPU_THROTTLE等），Azure SQL的资源配额限制（DTU/CPU/IO）会导致额外等待，这在本地环境往往不存在。
• lock_wait：如果存储过程执行完毕后仍存在锁等待（比如事务未提交导致的锁持有），该事件会在两者之间触发。
• sp_executesql_completed：若存储过程内部调用了sp_executesql执行动态SQL，这个事件会在对应的sp_statement_completed之后触发。
• connection_heartbeat：Azure SQL特有的连接心跳事件，部分场景下会在RPC调用收尾阶段触发，可能带来微小延迟。

Azure SQL环境差异排查重点 #

1. 优先聚焦等待事件：开启wait_info事件并过滤duration > 0的记录，对比本地和Azure的等待类型及耗时，重点关注LOG_IO_COMPLETION、PAGEIOLATCH_*这类和远程存储相关的等待。
2. 追踪事务日志写入耗时：通过transaction_log_write_completed事件查看日志写入的平均耗时，Azure SQL的异地冗余存储同步可能导致日志写入延迟远超本地。
3. 启用因果追踪：创建扩展事件会话时开启TRACK_CAUSALITY=ON，可以关联同一个会话中所有事件的时间线，精准定位sp_statement_completed最后一条记录到rpc_completed之间的时间消耗节点。
4. 示例扩展事件会话代码：

CREATE EVENT SESSION [RPC_Statement_Gap_Analysis] ON SERVER 
ADD EVENT sqlserver.sp_statement_completed(
    ACTION(sqlserver.sql_text, sqlserver.session_id, sqlserver.database_id)),
ADD EVENT sqlserver.rpc_completed(
    ACTION(sqlserver.sql_text, sqlserver.session_id, sqlserver.database_id)),
ADD EVENT sqlserver.wait_info(
    ACTION(sqlserver.session_id, sqlserver.sql_text)
    WHERE duration > 1000), -- 过滤耗时超过1ms的等待
ADD EVENT sqlserver.transaction_log_write_completed(
    ACTION(sqlserver.session_id, sqlserver.database_id)),
ADD EVENT sqlserver.lock_wait(
    ACTION(sqlserver.session_id, sqlserver.sql_text))
ADD TARGET package0.event_file(SET filename=N'RPC_Gap_Analysis.xel')
WITH (MAX_MEMORY=4096 KB, EVENT_RETENTION_MODE=ALLOW_SINGLE_EVENT_LOSS, MAX_DISPATCH_LATENCY=10 SECONDS, TRACK_CAUSALITY=ON, STARTUP_STATE=OFF)

总结 #

Azure SQL与本地环境的时长差异，大多源于平台特有的资源限制、远程存储IO延迟、事务日志同步机制等因素。通过捕获上述中间事件，结合因果追踪，可以精准定位导致sp_statement_completed和rpc_completed时长差异的具体原因。

内容的提问来源于stack exchange，提问作者Jim King