NetLogo Profiler独占时间大于包含时间的原因及修复方法

你给出的Profiler输出如下：

Profiler
BEGIN PROFILING DUMP
Sorted by Exclusive Time
Name                               Calls Incl T(ms) Excl T(ms) Excl/calls
COMPLETE-COOKING                   38741      0.711   4480.369      0.116
GET-RECIPE                         10701   2618.651   2618.651      0.245
GET-EQUIPMENT                      38741   1204.293   1204.293      0.031
SELECT-RECIPE-AT-TICK                990   9533.460    470.269      0.475
GIVE-RECIPE-REVIEW                 10701      4.294    449.523      0.042

正常情况下，过程的独占时间（过程自身代码执行的时间，不含调用其他子过程的耗时）应当小于等于包含时间（过程从调用到返回的全链路耗时，包含所有子过程耗时），你遇到的反向偏差属于统计异常，原因和修复方案如下：

异常原因 #

• 递归调用统计异常：如果COMPLETE-COOKING、GIVE-RECIPE-REVIEW存在直接递归（自己调用自己）或者间接递归（比如A调用B，B再调用A），旧版Profiler的调用栈统计逻辑会出现时间扣减错误，导致包含时间被大幅低估，远小于实际值。
• 过程执行被中断：如果过程中包含wait语句、等待其他Agent执行的阻塞逻辑、UI交互类语句，Profiler会把阻塞等待的时间计入独占时间，但包含时间仅在过程调用和返回两个节点统计，中间的阻塞时间不会被计入包含时间，最终出现数值反向。
• 版本已知bug：NetLogo 6.0及更早版本的Profiler扩展存在高频短过程的计时精度问题，你的这两个过程调用次数都超过一万次，单次耗时仅0.1ms左右，系统计时的最小单位误差累计后会导致包含时间统计严重偏差。

修复方案 #

• 排查并调整递归逻辑：先确认两个过程是否存在递归调用，如果有可以先改成迭代逻辑重新跑Profiler验证，也可以手动给过程加自定义计时逻辑，对比Profiler输出的数值是否准确。
• 关闭无关操作减少中断：跑Profiler前关闭视图更新、绘图、实时监视器等UI组件，执行__clear-all-and-reset-ticks重置模型状态后再启动性能统计，避免过程执行被UI线程中断导致统计异常。
• 升级软件版本：将NetLogo升级到6.1及以上版本，该版本内置的Profiler已经修复了高频调用的计时偏差问题，统计准确率大幅提升。
• 手动校验耗时：如果调整后还是存在偏差，可以在目标过程开头记录timer值，过程结束时计算时间差并累加得到实际总耗时，用手动统计的数值做性能优化的参考。

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