测量由 bukkit.event.Listener 处理的方法的运行时间

Question

我需要以毫秒 (#.### ms) 为单位准确测量 void 方法运行所需的准确时间，而测量不会影响结果。

通常我会简单地做这样的事情：

long startTime = System.nanoTime();
methodToTime();
long endTime = System.nanoTime();

long duration = (endTime - startTime);  //divide by 1000000 to get milliseconds.

这行不通，因为我的代码从不直接调用该方法；它只定义了它。

import org.bukkit.event.Listener;
import org.bukkit.event.EventHandler;
import org.bukkit.event.EventPriority;

public class BlockListener implements Listener {

    @EventHandler(priority = EventPriority.LOWEST, ignoreCancelled = true)
    public void onPlace(BlockPlaceEvent event) {

    //...
    }
}

在我正在开发的这个 Minecraft Bukkit 服务器中的某些方法的上下文中，1 毫秒很重要，这意味着我需要高精度来测量并优化对时间敏感的方法。

那么，在方法中添加任何代码肯定会影响方法的运行时间吗？

衡量这一点的最佳方法是什么？请和谢谢你

使用 ProtocolLib (1.16.5) 运行纸张/Spigot

Answer 1

绩效衡量总是会影响您的绩效，这就是为什么我建议将此功能设为可切换的原因。基于this answer，每个方法调用System.nanoTime() 两次大约需要200 个CPU 时钟周期，这不是很大的开销。

但是在测量了执行时间之后，你需要把它保存在某个地方或者打印一个日志。通常，I/O 操作是最昂贵的，因此最好避免在您的方法中阻塞输出。为此，您可以利用java.util.concurrent.BlockingQueue 或线程安全管道的其他实现（如Disruptor 或JCTools）并将捕获的结果记录在单独的守护线程中。

实施方案

public class ListenerPerformanceAuditor implements Listener {

    private final BlockingQueue<Long> executionTimeQueue;
    private final Listener auditedListener;

    public ListenerPerformanceAuditor(
            Listener auditedListener,
            BlockingQueue<Long> executionTimeQueue
    ) {
        this.auditedListener = auditedListener;
        this.executionTimeQueue = executionTimeQueue;
    }

    public void onPlace(BlockPlaceEvent event) {
        long startTime = System.nanoTime();
        auditedListener.onPlace(event);
        long endTime = System.nanoTime();

        long duration = (endTime - startTime);  //divide by 1000000 to get milliseconds.
        if(!executionTimeQueue.offer(duration)) {
            // if measuring each and every request isn't necessary,
            // this block can be eliminated, otherwise add error handling logic
        }
    }
}